Dec 13 14:12:55.969430 kernel: Booting Linux on physical CPU 0x0000000000 [0x410fd083] Dec 13 14:12:55.969468 kernel: Linux version 5.15.173-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 11.3.1_p20221209 p3) 11.3.1 20221209, GNU ld (Gentoo 2.39 p5) 2.39.0) #1 SMP PREEMPT Fri Dec 13 12:58:58 -00 2024 Dec 13 14:12:55.969491 kernel: efi: EFI v2.70 by EDK II Dec 13 14:12:55.969506 kernel: efi: SMBIOS=0x7bed0000 SMBIOS 3.0=0x7beb0000 ACPI=0x786e0000 ACPI 2.0=0x786e0014 MEMATTR=0x7b003a98 MEMRESERVE=0x7171cf98 Dec 13 14:12:55.969520 kernel: ACPI: Early table checksum verification disabled Dec 13 14:12:55.969533 kernel: ACPI: RSDP 0x00000000786E0014 000024 (v02 AMAZON) Dec 13 14:12:55.969549 kernel: ACPI: XSDT 0x00000000786D00E8 000064 (v01 AMAZON AMZNFACP 00000001 01000013) Dec 13 14:12:55.969563 kernel: ACPI: FACP 0x00000000786B0000 000114 (v06 AMAZON AMZNFACP 00000001 AMZN 00000001) Dec 13 14:12:55.969577 kernel: ACPI: DSDT 0x0000000078640000 00159D (v02 AMAZON AMZNDSDT 00000001 INTL 20160527) Dec 13 14:12:55.969590 kernel: ACPI: APIC 0x00000000786C0000 000108 (v04 AMAZON AMZNAPIC 00000001 AMZN 00000001) Dec 13 14:12:55.969608 kernel: ACPI: SPCR 0x00000000786A0000 000050 (v02 AMAZON AMZNSPCR 00000001 AMZN 00000001) Dec 13 14:12:55.969622 kernel: ACPI: GTDT 0x0000000078690000 000060 (v02 AMAZON AMZNGTDT 00000001 AMZN 00000001) Dec 13 14:12:55.969635 kernel: ACPI: MCFG 0x0000000078680000 00003C (v02 AMAZON AMZNMCFG 00000001 AMZN 00000001) Dec 13 14:12:55.969649 kernel: ACPI: SLIT 0x0000000078670000 00002D (v01 AMAZON AMZNSLIT 00000001 AMZN 00000001) Dec 13 14:12:55.969688 kernel: ACPI: IORT 0x0000000078660000 000078 (v01 AMAZON AMZNIORT 00000001 AMZN 00000001) Dec 13 14:12:55.969711 kernel: ACPI: PPTT 0x0000000078650000 0000EC (v01 AMAZON AMZNPPTT 00000001 AMZN 00000001) Dec 13 14:12:55.969726 kernel: ACPI: SPCR: console: uart,mmio,0x90a0000,115200 Dec 13 14:12:55.969741 kernel: earlycon: uart0 at MMIO 0x00000000090a0000 (options '115200') Dec 13 14:12:55.969755 kernel: printk: bootconsole [uart0] enabled Dec 13 14:12:55.969770 kernel: NUMA: Failed to initialise from firmware Dec 13 14:12:55.969785 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000004b5ffffff] Dec 13 14:12:55.969800 kernel: NUMA: NODE_DATA [mem 0x4b5843900-0x4b5848fff] Dec 13 14:12:55.969814 kernel: Zone ranges: Dec 13 14:12:55.969829 kernel: DMA [mem 0x0000000040000000-0x00000000ffffffff] Dec 13 14:12:55.969843 kernel: DMA32 empty Dec 13 14:12:55.969858 kernel: Normal [mem 0x0000000100000000-0x00000004b5ffffff] Dec 13 14:12:55.969876 kernel: Movable zone start for each node Dec 13 14:12:55.969892 kernel: Early memory node ranges Dec 13 14:12:55.969906 kernel: node 0: [mem 0x0000000040000000-0x000000007862ffff] Dec 13 14:12:55.969921 kernel: node 0: [mem 0x0000000078630000-0x000000007863ffff] Dec 13 14:12:55.969935 kernel: node 0: [mem 0x0000000078640000-0x00000000786effff] Dec 13 14:12:55.969950 kernel: node 0: [mem 0x00000000786f0000-0x000000007872ffff] Dec 13 14:12:55.969964 kernel: node 0: [mem 0x0000000078730000-0x000000007bbfffff] Dec 13 14:12:55.969978 kernel: node 0: [mem 0x000000007bc00000-0x000000007bfdffff] Dec 13 14:12:55.969993 kernel: node 0: [mem 0x000000007bfe0000-0x000000007fffffff] Dec 13 14:12:55.970008 kernel: node 0: [mem 0x0000000400000000-0x00000004b5ffffff] Dec 13 14:12:55.970022 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000004b5ffffff] Dec 13 14:12:55.970037 kernel: On node 0, zone Normal: 8192 pages in unavailable ranges Dec 13 14:12:55.970055 kernel: psci: probing for conduit method from ACPI. Dec 13 14:12:55.970070 kernel: psci: PSCIv1.0 detected in firmware. Dec 13 14:12:55.970092 kernel: psci: Using standard PSCI v0.2 function IDs Dec 13 14:12:55.970107 kernel: psci: Trusted OS migration not required Dec 13 14:12:55.970123 kernel: psci: SMC Calling Convention v1.1 Dec 13 14:12:55.970142 kernel: ACPI: SRAT not present Dec 13 14:12:55.970158 kernel: percpu: Embedded 30 pages/cpu s83032 r8192 d31656 u122880 Dec 13 14:12:55.970173 kernel: pcpu-alloc: s83032 r8192 d31656 u122880 alloc=30*4096 Dec 13 14:12:55.970194 kernel: pcpu-alloc: [0] 0 [0] 1 Dec 13 14:12:55.970228 kernel: Detected PIPT I-cache on CPU0 Dec 13 14:12:55.970258 kernel: CPU features: detected: GIC system register CPU interface Dec 13 14:12:55.970287 kernel: CPU features: detected: Spectre-v2 Dec 13 14:12:55.970304 kernel: CPU features: detected: Spectre-v3a Dec 13 14:12:55.970320 kernel: CPU features: detected: Spectre-BHB Dec 13 14:12:55.970337 kernel: CPU features: kernel page table isolation forced ON by KASLR Dec 13 14:12:55.970353 kernel: CPU features: detected: Kernel page table isolation (KPTI) Dec 13 14:12:55.970375 kernel: CPU features: detected: ARM erratum 1742098 Dec 13 14:12:55.970392 kernel: CPU features: detected: ARM errata 1165522, 1319367, or 1530923 Dec 13 14:12:55.970409 kernel: Built 1 zonelists, mobility grouping on. Total pages: 991872 Dec 13 14:12:55.970424 kernel: Policy zone: Normal Dec 13 14:12:55.970442 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=5997a8cf94b1df1856dc785f0a7074604bbf4c21fdcca24a1996021471a77601 Dec 13 14:12:55.970459 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Dec 13 14:12:55.970475 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Dec 13 14:12:55.970491 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Dec 13 14:12:55.970507 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Dec 13 14:12:55.970522 kernel: software IO TLB: mapped [mem 0x000000007c000000-0x0000000080000000] (64MB) Dec 13 14:12:55.970546 kernel: Memory: 3824524K/4030464K available (9792K kernel code, 2092K rwdata, 7576K rodata, 36416K init, 777K bss, 205940K reserved, 0K cma-reserved) Dec 13 14:12:55.970562 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Dec 13 14:12:55.970577 kernel: trace event string verifier disabled Dec 13 14:12:55.970592 kernel: rcu: Preemptible hierarchical RCU implementation. Dec 13 14:12:55.970608 kernel: rcu: RCU event tracing is enabled. Dec 13 14:12:55.970624 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Dec 13 14:12:55.970639 kernel: Trampoline variant of Tasks RCU enabled. Dec 13 14:12:55.970656 kernel: Tracing variant of Tasks RCU enabled. Dec 13 14:12:55.970692 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Dec 13 14:12:55.970709 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Dec 13 14:12:55.970724 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Dec 13 14:12:55.970739 kernel: GICv3: 96 SPIs implemented Dec 13 14:12:55.970762 kernel: GICv3: 0 Extended SPIs implemented Dec 13 14:12:55.970778 kernel: GICv3: Distributor has no Range Selector support Dec 13 14:12:55.970793 kernel: Root IRQ handler: gic_handle_irq Dec 13 14:12:55.970809 kernel: GICv3: 16 PPIs implemented Dec 13 14:12:55.970824 kernel: GICv3: CPU0: found redistributor 0 region 0:0x0000000010200000 Dec 13 14:12:55.970840 kernel: ACPI: SRAT not present Dec 13 14:12:55.970855 kernel: ITS [mem 0x10080000-0x1009ffff] Dec 13 14:12:55.970871 kernel: ITS@0x0000000010080000: allocated 8192 Devices @400090000 (indirect, esz 8, psz 64K, shr 1) Dec 13 14:12:55.970892 kernel: ITS@0x0000000010080000: allocated 8192 Interrupt Collections @4000a0000 (flat, esz 8, psz 64K, shr 1) Dec 13 14:12:55.970908 kernel: GICv3: using LPI property table @0x00000004000b0000 Dec 13 14:12:55.970924 kernel: ITS: Using hypervisor restricted LPI range [128] Dec 13 14:12:55.970944 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000004000d0000 Dec 13 14:12:55.970960 kernel: arch_timer: cp15 timer(s) running at 83.33MHz (virt). Dec 13 14:12:55.970978 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x13381ebeec, max_idle_ns: 440795203145 ns Dec 13 14:12:55.970993 kernel: sched_clock: 56 bits at 83MHz, resolution 12ns, wraps every 4398046511100ns Dec 13 14:12:55.971009 kernel: Console: colour dummy device 80x25 Dec 13 14:12:55.971040 kernel: printk: console [tty1] enabled Dec 13 14:12:55.971059 kernel: ACPI: Core revision 20210730 Dec 13 14:12:55.971075 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 166.66 BogoMIPS (lpj=83333) Dec 13 14:12:55.971092 kernel: pid_max: default: 32768 minimum: 301 Dec 13 14:12:55.971108 kernel: LSM: Security Framework initializing Dec 13 14:12:55.971130 kernel: SELinux: Initializing. Dec 13 14:12:55.971146 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Dec 13 14:12:55.971162 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Dec 13 14:12:55.971178 kernel: rcu: Hierarchical SRCU implementation. Dec 13 14:12:55.971194 kernel: Platform MSI: ITS@0x10080000 domain created Dec 13 14:12:55.971211 kernel: PCI/MSI: ITS@0x10080000 domain created Dec 13 14:12:55.971227 kernel: Remapping and enabling EFI services. Dec 13 14:12:55.971243 kernel: smp: Bringing up secondary CPUs ... Dec 13 14:12:55.971261 kernel: Detected PIPT I-cache on CPU1 Dec 13 14:12:55.971279 kernel: GICv3: CPU1: found redistributor 1 region 0:0x0000000010220000 Dec 13 14:12:55.971300 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000004000e0000 Dec 13 14:12:55.971315 kernel: CPU1: Booted secondary processor 0x0000000001 [0x410fd083] Dec 13 14:12:55.971330 kernel: smp: Brought up 1 node, 2 CPUs Dec 13 14:12:55.971346 kernel: SMP: Total of 2 processors activated. Dec 13 14:12:55.971361 kernel: CPU features: detected: 32-bit EL0 Support Dec 13 14:12:55.971376 kernel: CPU features: detected: 32-bit EL1 Support Dec 13 14:12:55.971392 kernel: CPU features: detected: CRC32 instructions Dec 13 14:12:55.971407 kernel: CPU: All CPU(s) started at EL1 Dec 13 14:12:55.971422 kernel: alternatives: patching kernel code Dec 13 14:12:55.971442 kernel: devtmpfs: initialized Dec 13 14:12:55.971457 kernel: KASLR disabled due to lack of seed Dec 13 14:12:55.971485 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Dec 13 14:12:55.971505 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Dec 13 14:12:55.971521 kernel: pinctrl core: initialized pinctrl subsystem Dec 13 14:12:55.971537 kernel: SMBIOS 3.0.0 present. Dec 13 14:12:55.971553 kernel: DMI: Amazon EC2 a1.large/, BIOS 1.0 11/1/2018 Dec 13 14:12:55.971569 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Dec 13 14:12:55.971585 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Dec 13 14:12:55.971601 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Dec 13 14:12:55.971618 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Dec 13 14:12:55.971638 kernel: audit: initializing netlink subsys (disabled) Dec 13 14:12:55.971655 kernel: audit: type=2000 audit(0.252:1): state=initialized audit_enabled=0 res=1 Dec 13 14:12:55.971692 kernel: thermal_sys: Registered thermal governor 'step_wise' Dec 13 14:12:55.971709 kernel: cpuidle: using governor menu Dec 13 14:12:55.971725 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Dec 13 14:12:55.971746 kernel: ASID allocator initialised with 32768 entries Dec 13 14:12:55.971763 kernel: ACPI: bus type PCI registered Dec 13 14:12:55.971779 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Dec 13 14:12:55.971795 kernel: Serial: AMBA PL011 UART driver Dec 13 14:12:55.971811 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Dec 13 14:12:55.971827 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Dec 13 14:12:55.971843 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Dec 13 14:12:55.971860 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Dec 13 14:12:55.971876 kernel: cryptd: max_cpu_qlen set to 1000 Dec 13 14:12:55.971896 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Dec 13 14:12:55.971913 kernel: ACPI: Added _OSI(Module Device) Dec 13 14:12:55.971929 kernel: ACPI: Added _OSI(Processor Device) Dec 13 14:12:55.971945 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Dec 13 14:12:55.971961 kernel: ACPI: Added _OSI(Processor Aggregator Device) Dec 13 14:12:55.971977 kernel: ACPI: Added _OSI(Linux-Dell-Video) Dec 13 14:12:55.971993 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Dec 13 14:12:55.972009 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Dec 13 14:12:55.972026 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Dec 13 14:12:55.972046 kernel: ACPI: Interpreter enabled Dec 13 14:12:55.972063 kernel: ACPI: Using GIC for interrupt routing Dec 13 14:12:55.972079 kernel: ACPI: MCFG table detected, 1 entries Dec 13 14:12:55.972095 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-0f]) Dec 13 14:12:55.972410 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Dec 13 14:12:55.972616 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Dec 13 14:12:55.972831 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Dec 13 14:12:55.973020 kernel: acpi PNP0A08:00: ECAM area [mem 0x20000000-0x20ffffff] reserved by PNP0C02:00 Dec 13 14:12:55.973222 kernel: acpi PNP0A08:00: ECAM at [mem 0x20000000-0x20ffffff] for [bus 00-0f] Dec 13 14:12:55.973247 kernel: ACPI: Remapped I/O 0x000000001fff0000 to [io 0x0000-0xffff window] Dec 13 14:12:55.973264 kernel: acpiphp: Slot [1] registered Dec 13 14:12:55.973280 kernel: acpiphp: Slot [2] registered Dec 13 14:12:55.973297 kernel: acpiphp: Slot [3] registered Dec 13 14:12:55.973312 kernel: acpiphp: Slot [4] registered Dec 13 14:12:55.973328 kernel: acpiphp: Slot [5] registered Dec 13 14:12:55.973344 kernel: acpiphp: Slot [6] registered Dec 13 14:12:55.973360 kernel: acpiphp: Slot [7] registered Dec 13 14:12:55.973381 kernel: acpiphp: Slot [8] registered Dec 13 14:12:55.973397 kernel: acpiphp: Slot [9] registered Dec 13 14:12:55.973413 kernel: acpiphp: Slot [10] registered Dec 13 14:12:55.973429 kernel: acpiphp: Slot [11] registered Dec 13 14:12:55.973445 kernel: acpiphp: Slot [12] registered Dec 13 14:12:55.973461 kernel: acpiphp: Slot [13] registered Dec 13 14:12:55.973476 kernel: acpiphp: Slot [14] registered Dec 13 14:12:55.973492 kernel: acpiphp: Slot [15] registered Dec 13 14:12:55.973508 kernel: acpiphp: Slot [16] registered Dec 13 14:12:55.973528 kernel: acpiphp: Slot [17] registered Dec 13 14:12:55.973545 kernel: acpiphp: Slot [18] registered Dec 13 14:12:55.973560 kernel: acpiphp: Slot [19] registered Dec 13 14:12:55.973576 kernel: acpiphp: Slot [20] registered Dec 13 14:12:55.973592 kernel: acpiphp: Slot [21] registered Dec 13 14:12:55.973608 kernel: acpiphp: Slot [22] registered Dec 13 14:12:55.973624 kernel: acpiphp: Slot [23] registered Dec 13 14:12:55.973640 kernel: acpiphp: Slot [24] registered Dec 13 14:12:55.973655 kernel: acpiphp: Slot [25] registered Dec 13 14:12:55.973690 kernel: acpiphp: Slot [26] registered Dec 13 14:12:55.973711 kernel: acpiphp: Slot [27] registered Dec 13 14:12:55.973728 kernel: acpiphp: Slot [28] registered Dec 13 14:12:55.973743 kernel: acpiphp: Slot [29] registered Dec 13 14:12:55.973759 kernel: acpiphp: Slot [30] registered Dec 13 14:12:55.973775 kernel: acpiphp: Slot [31] registered Dec 13 14:12:55.973791 kernel: PCI host bridge to bus 0000:00 Dec 13 14:12:55.973991 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xffffffff window] Dec 13 14:12:55.974164 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Dec 13 14:12:55.974338 kernel: pci_bus 0000:00: root bus resource [mem 0x400000000000-0x407fffffffff window] Dec 13 14:12:55.974506 kernel: pci_bus 0000:00: root bus resource [bus 00-0f] Dec 13 14:12:55.974747 kernel: pci 0000:00:00.0: [1d0f:0200] type 00 class 0x060000 Dec 13 14:12:55.974993 kernel: pci 0000:00:01.0: [1d0f:8250] type 00 class 0x070003 Dec 13 14:12:55.975194 kernel: pci 0000:00:01.0: reg 0x10: [mem 0x80118000-0x80118fff] Dec 13 14:12:55.975400 kernel: pci 0000:00:04.0: [1d0f:8061] type 00 class 0x010802 Dec 13 14:12:55.975598 kernel: pci 0000:00:04.0: reg 0x10: [mem 0x80114000-0x80117fff] Dec 13 14:12:55.975810 kernel: pci 0000:00:04.0: PME# supported from D0 D1 D2 D3hot D3cold Dec 13 14:12:55.976022 kernel: pci 0000:00:05.0: [1d0f:ec20] type 00 class 0x020000 Dec 13 14:12:55.976217 kernel: pci 0000:00:05.0: reg 0x10: [mem 0x80110000-0x80113fff] Dec 13 14:12:55.976414 kernel: pci 0000:00:05.0: reg 0x18: [mem 0x80000000-0x800fffff pref] Dec 13 14:12:55.976632 kernel: pci 0000:00:05.0: reg 0x20: [mem 0x80100000-0x8010ffff] Dec 13 14:12:55.985131 kernel: pci 0000:00:05.0: PME# supported from D0 D1 D2 D3hot D3cold Dec 13 14:12:55.985372 kernel: pci 0000:00:05.0: BAR 2: assigned [mem 0x80000000-0x800fffff pref] Dec 13 14:12:55.985581 kernel: pci 0000:00:05.0: BAR 4: assigned [mem 0x80100000-0x8010ffff] Dec 13 14:12:55.985803 kernel: pci 0000:00:04.0: BAR 0: assigned [mem 0x80110000-0x80113fff] Dec 13 14:12:55.985997 kernel: pci 0000:00:05.0: BAR 0: assigned [mem 0x80114000-0x80117fff] Dec 13 14:12:55.986205 kernel: pci 0000:00:01.0: BAR 0: assigned [mem 0x80118000-0x80118fff] Dec 13 14:12:55.986395 kernel: pci_bus 0000:00: resource 4 [mem 0x80000000-0xffffffff window] Dec 13 14:12:55.986580 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Dec 13 14:12:56.002716 kernel: pci_bus 0000:00: resource 6 [mem 0x400000000000-0x407fffffffff window] Dec 13 14:12:56.002773 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Dec 13 14:12:56.002792 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Dec 13 14:12:56.002809 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Dec 13 14:12:56.002826 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Dec 13 14:12:56.002844 kernel: iommu: Default domain type: Translated Dec 13 14:12:56.002860 kernel: iommu: DMA domain TLB invalidation policy: strict mode Dec 13 14:12:56.002877 kernel: vgaarb: loaded Dec 13 14:12:56.002894 kernel: pps_core: LinuxPPS API ver. 1 registered Dec 13 14:12:56.002921 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Dec 13 14:12:56.002938 kernel: PTP clock support registered Dec 13 14:12:56.002954 kernel: Registered efivars operations Dec 13 14:12:56.002970 kernel: clocksource: Switched to clocksource arch_sys_counter Dec 13 14:12:56.002987 kernel: VFS: Disk quotas dquot_6.6.0 Dec 13 14:12:56.003003 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Dec 13 14:12:56.003019 kernel: pnp: PnP ACPI init Dec 13 14:12:56.003265 kernel: system 00:00: [mem 0x20000000-0x2fffffff] could not be reserved Dec 13 14:12:56.003296 kernel: pnp: PnP ACPI: found 1 devices Dec 13 14:12:56.003314 kernel: NET: Registered PF_INET protocol family Dec 13 14:12:56.003331 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Dec 13 14:12:56.003348 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Dec 13 14:12:56.003364 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Dec 13 14:12:56.003381 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Dec 13 14:12:56.003397 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Dec 13 14:12:56.003414 kernel: TCP: Hash tables configured (established 32768 bind 32768) Dec 13 14:12:56.003430 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Dec 13 14:12:56.003451 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Dec 13 14:12:56.003468 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Dec 13 14:12:56.003484 kernel: PCI: CLS 0 bytes, default 64 Dec 13 14:12:56.003501 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 3 counters available Dec 13 14:12:56.003517 kernel: kvm [1]: HYP mode not available Dec 13 14:12:56.003534 kernel: Initialise system trusted keyrings Dec 13 14:12:56.003553 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Dec 13 14:12:56.003571 kernel: Key type asymmetric registered Dec 13 14:12:56.003587 kernel: Asymmetric key parser 'x509' registered Dec 13 14:12:56.003608 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Dec 13 14:12:56.003626 kernel: io scheduler mq-deadline registered Dec 13 14:12:56.003643 kernel: io scheduler kyber registered Dec 13 14:12:56.003680 kernel: io scheduler bfq registered Dec 13 14:12:56.003932 kernel: pl061_gpio ARMH0061:00: PL061 GPIO chip registered Dec 13 14:12:56.003958 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Dec 13 14:12:56.003975 kernel: ACPI: button: Power Button [PWRB] Dec 13 14:12:56.003992 kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0E:00/input/input1 Dec 13 14:12:56.004015 kernel: ACPI: button: Sleep Button [SLPB] Dec 13 14:12:56.004032 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Dec 13 14:12:56.004050 kernel: ACPI: \_SB_.PCI0.GSI2: Enabled at IRQ 37 Dec 13 14:12:56.004290 kernel: serial 0000:00:01.0: enabling device (0010 -> 0012) Dec 13 14:12:56.004316 kernel: printk: console [ttyS0] disabled Dec 13 14:12:56.004333 kernel: 0000:00:01.0: ttyS0 at MMIO 0x80118000 (irq = 14, base_baud = 115200) is a 16550A Dec 13 14:12:56.004349 kernel: printk: console [ttyS0] enabled Dec 13 14:12:56.004366 kernel: printk: bootconsole [uart0] disabled Dec 13 14:12:56.004382 kernel: thunder_xcv, ver 1.0 Dec 13 14:12:56.004399 kernel: thunder_bgx, ver 1.0 Dec 13 14:12:56.004422 kernel: nicpf, ver 1.0 Dec 13 14:12:56.004438 kernel: nicvf, ver 1.0 Dec 13 14:12:56.004733 kernel: rtc-efi rtc-efi.0: registered as rtc0 Dec 13 14:12:56.004945 kernel: rtc-efi rtc-efi.0: setting system clock to 2024-12-13T14:12:55 UTC (1734099175) Dec 13 14:12:56.004969 kernel: hid: raw HID events driver (C) Jiri Kosina Dec 13 14:12:56.004986 kernel: NET: Registered PF_INET6 protocol family Dec 13 14:12:56.005003 kernel: Segment Routing with IPv6 Dec 13 14:12:56.005019 kernel: In-situ OAM (IOAM) with IPv6 Dec 13 14:12:56.005042 kernel: NET: Registered PF_PACKET protocol family Dec 13 14:12:56.005058 kernel: Key type dns_resolver registered Dec 13 14:12:56.005075 kernel: registered taskstats version 1 Dec 13 14:12:56.005091 kernel: Loading compiled-in X.509 certificates Dec 13 14:12:56.005110 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.15.173-flatcar: e011ba9949ade5a6d03f7a5e28171f7f59e70f8a' Dec 13 14:12:56.005126 kernel: Key type .fscrypt registered Dec 13 14:12:56.005142 kernel: Key type fscrypt-provisioning registered Dec 13 14:12:56.005158 kernel: ima: No TPM chip found, activating TPM-bypass! Dec 13 14:12:56.005175 kernel: ima: Allocated hash algorithm: sha1 Dec 13 14:12:56.005196 kernel: ima: No architecture policies found Dec 13 14:12:56.005212 kernel: clk: Disabling unused clocks Dec 13 14:12:56.005228 kernel: Freeing unused kernel memory: 36416K Dec 13 14:12:56.005244 kernel: Run /init as init process Dec 13 14:12:56.005260 kernel: with arguments: Dec 13 14:12:56.005276 kernel: /init Dec 13 14:12:56.005292 kernel: with environment: Dec 13 14:12:56.005307 kernel: HOME=/ Dec 13 14:12:56.005323 kernel: TERM=linux Dec 13 14:12:56.005343 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Dec 13 14:12:56.005365 systemd[1]: systemd 252 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) Dec 13 14:12:56.005386 systemd[1]: Detected virtualization amazon. Dec 13 14:12:56.005405 systemd[1]: Detected architecture arm64. Dec 13 14:12:56.005422 systemd[1]: Running in initrd. Dec 13 14:12:56.005439 systemd[1]: No hostname configured, using default hostname. Dec 13 14:12:56.005457 systemd[1]: Hostname set to . Dec 13 14:12:56.005479 systemd[1]: Initializing machine ID from VM UUID. Dec 13 14:12:56.005497 systemd[1]: Queued start job for default target initrd.target. Dec 13 14:12:56.005515 systemd[1]: Started systemd-ask-password-console.path. Dec 13 14:12:56.005532 systemd[1]: Reached target cryptsetup.target. Dec 13 14:12:56.005550 systemd[1]: Reached target paths.target. Dec 13 14:12:56.005567 systemd[1]: Reached target slices.target. Dec 13 14:12:56.005584 systemd[1]: Reached target swap.target. Dec 13 14:12:56.005602 systemd[1]: Reached target timers.target. Dec 13 14:12:56.005624 systemd[1]: Listening on iscsid.socket. Dec 13 14:12:56.005642 systemd[1]: Listening on iscsiuio.socket. Dec 13 14:12:56.008052 systemd[1]: Listening on systemd-journald-audit.socket. Dec 13 14:12:56.008100 systemd[1]: Listening on systemd-journald-dev-log.socket. Dec 13 14:12:56.008120 systemd[1]: Listening on systemd-journald.socket. Dec 13 14:12:56.008138 systemd[1]: Listening on systemd-networkd.socket. Dec 13 14:12:56.008157 systemd[1]: Listening on systemd-udevd-control.socket. Dec 13 14:12:56.008175 systemd[1]: Listening on systemd-udevd-kernel.socket. Dec 13 14:12:56.008202 systemd[1]: Reached target sockets.target. Dec 13 14:12:56.008221 systemd[1]: Starting kmod-static-nodes.service... Dec 13 14:12:56.008239 systemd[1]: Finished network-cleanup.service. Dec 13 14:12:56.008257 systemd[1]: Starting systemd-fsck-usr.service... Dec 13 14:12:56.008275 systemd[1]: Starting systemd-journald.service... Dec 13 14:12:56.008293 systemd[1]: Starting systemd-modules-load.service... Dec 13 14:12:56.008311 systemd[1]: Starting systemd-resolved.service... Dec 13 14:12:56.008331 systemd[1]: Starting systemd-vconsole-setup.service... Dec 13 14:12:56.008349 systemd[1]: Finished kmod-static-nodes.service. Dec 13 14:12:56.008374 kernel: audit: type=1130 audit(1734099175.957:2): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:56.008394 systemd[1]: Finished systemd-fsck-usr.service. Dec 13 14:12:56.008413 kernel: audit: type=1130 audit(1734099175.969:3): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-usr comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:56.008431 systemd[1]: Finished systemd-vconsole-setup.service. Dec 13 14:12:56.008451 systemd[1]: Starting dracut-cmdline-ask.service... Dec 13 14:12:56.008469 kernel: audit: type=1130 audit(1734099175.981:4): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:56.008487 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... Dec 13 14:12:56.008517 systemd-journald[309]: Journal started Dec 13 14:12:56.008748 systemd-journald[309]: Runtime Journal (/run/log/journal/ec2ab2fa919ba3cc00777d8eccc0dea1) is 8.0M, max 75.4M, 67.4M free. Dec 13 14:12:55.957000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:55.969000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-usr comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:55.981000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:55.981219 systemd-modules-load[310]: Inserted module 'overlay' Dec 13 14:12:56.032719 systemd[1]: Started systemd-journald.service. Dec 13 14:12:56.032826 kernel: audit: type=1130 audit(1734099176.020:5): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:56.020000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:56.057161 systemd[1]: Finished dracut-cmdline-ask.service. Dec 13 14:12:56.057000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:56.059783 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. Dec 13 14:12:56.082698 kernel: audit: type=1130 audit(1734099176.057:6): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:56.082765 kernel: audit: type=1130 audit(1734099176.067:7): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:56.082790 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Dec 13 14:12:56.067000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:56.071211 systemd[1]: Starting dracut-cmdline.service... Dec 13 14:12:56.096789 systemd-resolved[311]: Positive Trust Anchors: Dec 13 14:12:56.098641 systemd-resolved[311]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Dec 13 14:12:56.101527 systemd-resolved[311]: 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 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Dec 13 14:12:56.112563 kernel: Bridge firewalling registered Dec 13 14:12:56.104671 systemd-modules-load[310]: Inserted module 'br_netfilter' Dec 13 14:12:56.126176 dracut-cmdline[326]: dracut-dracut-053 Dec 13 14:12:56.137897 kernel: SCSI subsystem initialized Dec 13 14:12:56.143244 dracut-cmdline[326]: 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=5997a8cf94b1df1856dc785f0a7074604bbf4c21fdcca24a1996021471a77601 Dec 13 14:12:56.166781 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Dec 13 14:12:56.166822 kernel: device-mapper: uevent: version 1.0.3 Dec 13 14:12:56.169627 kernel: device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com Dec 13 14:12:56.176837 systemd-modules-load[310]: Inserted module 'dm_multipath' Dec 13 14:12:56.179754 systemd[1]: Finished systemd-modules-load.service. Dec 13 14:12:56.191348 kernel: audit: type=1130 audit(1734099176.178:8): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:56.178000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:56.189244 systemd[1]: Starting systemd-sysctl.service... Dec 13 14:12:56.212344 systemd[1]: Finished systemd-sysctl.service. Dec 13 14:12:56.222243 kernel: audit: type=1130 audit(1734099176.212:9): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:56.212000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:56.301701 kernel: Loading iSCSI transport class v2.0-870. Dec 13 14:12:56.323734 kernel: iscsi: registered transport (tcp) Dec 13 14:12:56.350355 kernel: iscsi: registered transport (qla4xxx) Dec 13 14:12:56.350425 kernel: QLogic iSCSI HBA Driver Dec 13 14:12:56.572339 systemd-resolved[311]: Defaulting to hostname 'linux'. Dec 13 14:12:56.574834 kernel: random: crng init done Dec 13 14:12:56.576876 systemd[1]: Started systemd-resolved.service. Dec 13 14:12:56.588072 kernel: audit: type=1130 audit(1734099176.577:10): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:56.577000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:56.586532 systemd[1]: Reached target nss-lookup.target. Dec 13 14:12:56.604874 systemd[1]: Finished dracut-cmdline.service. Dec 13 14:12:56.605000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:56.609275 systemd[1]: Starting dracut-pre-udev.service... Dec 13 14:12:56.674705 kernel: raid6: neonx8 gen() 6442 MB/s Dec 13 14:12:56.692693 kernel: raid6: neonx8 xor() 4705 MB/s Dec 13 14:12:56.710693 kernel: raid6: neonx4 gen() 6549 MB/s Dec 13 14:12:56.728691 kernel: raid6: neonx4 xor() 4873 MB/s Dec 13 14:12:56.746691 kernel: raid6: neonx2 gen() 5803 MB/s Dec 13 14:12:56.764691 kernel: raid6: neonx2 xor() 4489 MB/s Dec 13 14:12:56.782691 kernel: raid6: neonx1 gen() 4475 MB/s Dec 13 14:12:56.800691 kernel: raid6: neonx1 xor() 3653 MB/s Dec 13 14:12:56.818692 kernel: raid6: int64x8 gen() 3420 MB/s Dec 13 14:12:56.836692 kernel: raid6: int64x8 xor() 2084 MB/s Dec 13 14:12:56.854691 kernel: raid6: int64x4 gen() 3791 MB/s Dec 13 14:12:56.872692 kernel: raid6: int64x4 xor() 2188 MB/s Dec 13 14:12:56.890692 kernel: raid6: int64x2 gen() 3599 MB/s Dec 13 14:12:56.908692 kernel: raid6: int64x2 xor() 1943 MB/s Dec 13 14:12:56.926693 kernel: raid6: int64x1 gen() 2767 MB/s Dec 13 14:12:56.945828 kernel: raid6: int64x1 xor() 1447 MB/s Dec 13 14:12:56.945859 kernel: raid6: using algorithm neonx4 gen() 6549 MB/s Dec 13 14:12:56.945883 kernel: raid6: .... xor() 4873 MB/s, rmw enabled Dec 13 14:12:56.947424 kernel: raid6: using neon recovery algorithm Dec 13 14:12:56.965708 kernel: xor: measuring software checksum speed Dec 13 14:12:56.969035 kernel: 8regs : 8709 MB/sec Dec 13 14:12:56.969078 kernel: 32regs : 11079 MB/sec Dec 13 14:12:56.970868 kernel: arm64_neon : 9215 MB/sec Dec 13 14:12:56.970898 kernel: xor: using function: 32regs (11079 MB/sec) Dec 13 14:12:57.062718 kernel: Btrfs loaded, crc32c=crc32c-generic, zoned=no, fsverity=no Dec 13 14:12:57.080213 systemd[1]: Finished dracut-pre-udev.service. Dec 13 14:12:57.080000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:57.082000 audit: BPF prog-id=7 op=LOAD Dec 13 14:12:57.082000 audit: BPF prog-id=8 op=LOAD Dec 13 14:12:57.084586 systemd[1]: Starting systemd-udevd.service... Dec 13 14:12:57.115113 systemd-udevd[508]: Using default interface naming scheme 'v252'. Dec 13 14:12:57.126079 systemd[1]: Started systemd-udevd.service. Dec 13 14:12:57.126000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:57.130565 systemd[1]: Starting dracut-pre-trigger.service... Dec 13 14:12:57.168300 dracut-pre-trigger[513]: rd.md=0: removing MD RAID activation Dec 13 14:12:57.229056 systemd[1]: Finished dracut-pre-trigger.service. Dec 13 14:12:57.230000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:57.233312 systemd[1]: Starting systemd-udev-trigger.service... Dec 13 14:12:57.336890 systemd[1]: Finished systemd-udev-trigger.service. Dec 13 14:12:57.337000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:57.464814 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Dec 13 14:12:57.464893 kernel: ena 0000:00:05.0: enabling device (0010 -> 0012) Dec 13 14:12:57.483729 kernel: ena 0000:00:05.0: ENA device version: 0.10 Dec 13 14:12:57.483959 kernel: ena 0000:00:05.0: ENA controller version: 0.0.1 implementation version 1 Dec 13 14:12:57.484173 kernel: ACPI: \_SB_.PCI0.GSI0: Enabled at IRQ 35 Dec 13 14:12:57.484199 kernel: nvme nvme0: pci function 0000:00:04.0 Dec 13 14:12:57.484531 kernel: ena 0000:00:05.0: Elastic Network Adapter (ENA) found at mem 80114000, mac addr 06:0a:54:5e:00:85 Dec 13 14:12:57.487699 kernel: nvme nvme0: 2/0/0 default/read/poll queues Dec 13 14:12:57.495387 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Dec 13 14:12:57.495429 kernel: GPT:9289727 != 16777215 Dec 13 14:12:57.495453 kernel: GPT:Alternate GPT header not at the end of the disk. Dec 13 14:12:57.497441 kernel: GPT:9289727 != 16777215 Dec 13 14:12:57.498680 kernel: GPT: Use GNU Parted to correct GPT errors. Dec 13 14:12:57.501796 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Dec 13 14:12:57.505423 (udev-worker)[569]: Network interface NamePolicy= disabled on kernel command line. Dec 13 14:12:57.577715 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/nvme0n1p6 scanned by (udev-worker) (573) Dec 13 14:12:57.634496 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device. Dec 13 14:12:57.652552 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device. Dec 13 14:12:57.655039 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device. Dec 13 14:12:57.665164 systemd[1]: Starting disk-uuid.service... Dec 13 14:12:57.683851 disk-uuid[661]: Primary Header is updated. Dec 13 14:12:57.683851 disk-uuid[661]: Secondary Entries is updated. Dec 13 14:12:57.683851 disk-uuid[661]: Secondary Header is updated. Dec 13 14:12:57.712129 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Dec 13 14:12:57.730325 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device. Dec 13 14:12:58.710287 disk-uuid[667]: The operation has completed successfully. Dec 13 14:12:58.712783 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Dec 13 14:12:58.906733 systemd[1]: disk-uuid.service: Deactivated successfully. Dec 13 14:12:58.908937 systemd[1]: Finished disk-uuid.service. Dec 13 14:12:58.910000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=disk-uuid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:58.910000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=disk-uuid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:58.941521 systemd[1]: Starting verity-setup.service... Dec 13 14:12:58.985723 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Dec 13 14:12:59.094432 systemd[1]: Found device dev-mapper-usr.device. Dec 13 14:12:59.099391 systemd[1]: Finished verity-setup.service. Dec 13 14:12:59.100000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=verity-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:59.104043 systemd[1]: Mounting sysusr-usr.mount... Dec 13 14:12:59.197751 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: norecovery. Quota mode: none. Dec 13 14:12:59.198402 systemd[1]: Mounted sysusr-usr.mount. Dec 13 14:12:59.201195 systemd[1]: afterburn-network-kargs.service was skipped because no trigger condition checks were met. Dec 13 14:12:59.204894 systemd[1]: Starting ignition-setup.service... Dec 13 14:12:59.211421 systemd[1]: Starting parse-ip-for-networkd.service... Dec 13 14:12:59.247434 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Dec 13 14:12:59.247497 kernel: BTRFS info (device nvme0n1p6): using free space tree Dec 13 14:12:59.249580 kernel: BTRFS info (device nvme0n1p6): has skinny extents Dec 13 14:12:59.262711 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Dec 13 14:12:59.282261 systemd[1]: mnt-oem.mount: Deactivated successfully. Dec 13 14:12:59.300461 systemd[1]: Finished ignition-setup.service. Dec 13 14:12:59.302000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:59.304827 systemd[1]: Starting ignition-fetch-offline.service... Dec 13 14:12:59.365410 systemd[1]: Finished parse-ip-for-networkd.service. Dec 13 14:12:59.366000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=parse-ip-for-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:59.367000 audit: BPF prog-id=9 op=LOAD Dec 13 14:12:59.371267 systemd[1]: Starting systemd-networkd.service... Dec 13 14:12:59.417623 systemd-networkd[1195]: lo: Link UP Dec 13 14:12:59.417646 systemd-networkd[1195]: lo: Gained carrier Dec 13 14:12:59.419256 systemd-networkd[1195]: Enumeration completed Dec 13 14:12:59.419726 systemd-networkd[1195]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Dec 13 14:12:59.419967 systemd[1]: Started systemd-networkd.service. Dec 13 14:12:59.427000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:59.429602 systemd[1]: Reached target network.target. Dec 13 14:12:59.433593 systemd-networkd[1195]: eth0: Link UP Dec 13 14:12:59.433617 systemd-networkd[1195]: eth0: Gained carrier Dec 13 14:12:59.438200 systemd[1]: Starting iscsiuio.service... Dec 13 14:12:59.447829 systemd-networkd[1195]: eth0: DHCPv4 address 172.31.24.120/20, gateway 172.31.16.1 acquired from 172.31.16.1 Dec 13 14:12:59.454531 systemd[1]: Started iscsiuio.service. Dec 13 14:12:59.455000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsiuio comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:59.459068 systemd[1]: Starting iscsid.service... Dec 13 14:12:59.468195 iscsid[1200]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Dec 13 14:12:59.468195 iscsid[1200]: iscsid: Warning: InitiatorName file /etc/iscsi/initiatorname.iscsi does not exist or does not contain a properly formatted InitiatorName. If using software iscsi (iscsi_tcp or ib_iser) or partial offload (bnx2i or cxgbi iscsi), you may not be able to log into or discover targets. Please create a file /etc/iscsi/initiatorname.iscsi that contains a sting with the format: InitiatorName=iqn.yyyy-mm.[:identifier]. Dec 13 14:12:59.468195 iscsid[1200]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Dec 13 14:12:59.468195 iscsid[1200]: If using hardware iscsi like qla4xxx this message can be ignored. Dec 13 14:12:59.481000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:59.485969 iscsid[1200]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Dec 13 14:12:59.485969 iscsid[1200]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Dec 13 14:12:59.480720 systemd[1]: Started iscsid.service. Dec 13 14:12:59.493697 systemd[1]: Starting dracut-initqueue.service... Dec 13 14:12:59.518793 systemd[1]: Finished dracut-initqueue.service. Dec 13 14:12:59.517000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-initqueue comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:59.521486 systemd[1]: Reached target remote-fs-pre.target. Dec 13 14:12:59.523510 systemd[1]: Reached target remote-cryptsetup.target. Dec 13 14:12:59.526593 systemd[1]: Reached target remote-fs.target. Dec 13 14:12:59.531774 systemd[1]: Starting dracut-pre-mount.service... Dec 13 14:12:59.551693 systemd[1]: Finished dracut-pre-mount.service. Dec 13 14:12:59.553000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:00.028350 ignition[1145]: Ignition 2.14.0 Dec 13 14:13:00.028377 ignition[1145]: Stage: fetch-offline Dec 13 14:13:00.028800 ignition[1145]: reading system config file "/usr/lib/ignition/base.d/base.ign" Dec 13 14:13:00.028863 ignition[1145]: parsing config with SHA512: 6629d8e825d60c9c9d4629d8547ef9a0b839d6b01b7f61a481a1f23308c924b8b0bbf10cae7f7fe3bcaf88b23d1a81baa7771c3670728d4d2a1e665216a1de7b Dec 13 14:13:00.048630 ignition[1145]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Dec 13 14:13:00.049622 ignition[1145]: Ignition finished successfully Dec 13 14:13:00.054000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch-offline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:00.054385 systemd[1]: Finished ignition-fetch-offline.service. Dec 13 14:13:00.067893 kernel: kauditd_printk_skb: 18 callbacks suppressed Dec 13 14:13:00.067930 kernel: audit: type=1130 audit(1734099180.054:29): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch-offline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:00.057675 systemd[1]: Starting ignition-fetch.service... Dec 13 14:13:00.083320 ignition[1219]: Ignition 2.14.0 Dec 13 14:13:00.083862 ignition[1219]: Stage: fetch Dec 13 14:13:00.084164 ignition[1219]: reading system config file "/usr/lib/ignition/base.d/base.ign" Dec 13 14:13:00.084222 ignition[1219]: parsing config with SHA512: 6629d8e825d60c9c9d4629d8547ef9a0b839d6b01b7f61a481a1f23308c924b8b0bbf10cae7f7fe3bcaf88b23d1a81baa7771c3670728d4d2a1e665216a1de7b Dec 13 14:13:00.098324 ignition[1219]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Dec 13 14:13:00.101890 ignition[1219]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Dec 13 14:13:00.114693 ignition[1219]: INFO : PUT result: OK Dec 13 14:13:00.117918 ignition[1219]: DEBUG : parsed url from cmdline: "" Dec 13 14:13:00.117918 ignition[1219]: INFO : no config URL provided Dec 13 14:13:00.117918 ignition[1219]: INFO : reading system config file "/usr/lib/ignition/user.ign" Dec 13 14:13:00.123386 ignition[1219]: INFO : no config at "/usr/lib/ignition/user.ign" Dec 13 14:13:00.123386 ignition[1219]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Dec 13 14:13:00.127452 ignition[1219]: INFO : PUT result: OK Dec 13 14:13:00.127452 ignition[1219]: INFO : GET http://169.254.169.254/2019-10-01/user-data: attempt #1 Dec 13 14:13:00.134471 ignition[1219]: INFO : GET result: OK Dec 13 14:13:00.135860 ignition[1219]: DEBUG : parsing config with SHA512: fea5cf72f981896f8a2438cf5004ae5580bd1d610d88e38f6d6d5ea998aef9e028623e3ecf12a2aec6fe2927c11039abc0219744cf4acd3318dcef53beb2cc58 Dec 13 14:13:00.147204 unknown[1219]: fetched base config from "system" Dec 13 14:13:00.149013 unknown[1219]: fetched base config from "system" Dec 13 14:13:00.150190 unknown[1219]: fetched user config from "aws" Dec 13 14:13:00.152717 ignition[1219]: fetch: fetch complete Dec 13 14:13:00.153004 ignition[1219]: fetch: fetch passed Dec 13 14:13:00.153092 ignition[1219]: Ignition finished successfully Dec 13 14:13:00.158728 systemd[1]: Finished ignition-fetch.service. Dec 13 14:13:00.159000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:00.171706 kernel: audit: type=1130 audit(1734099180.159:30): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:00.169936 systemd[1]: Starting ignition-kargs.service... Dec 13 14:13:00.187782 ignition[1225]: Ignition 2.14.0 Dec 13 14:13:00.187810 ignition[1225]: Stage: kargs Dec 13 14:13:00.188111 ignition[1225]: reading system config file "/usr/lib/ignition/base.d/base.ign" Dec 13 14:13:00.188169 ignition[1225]: parsing config with SHA512: 6629d8e825d60c9c9d4629d8547ef9a0b839d6b01b7f61a481a1f23308c924b8b0bbf10cae7f7fe3bcaf88b23d1a81baa7771c3670728d4d2a1e665216a1de7b Dec 13 14:13:00.202698 ignition[1225]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Dec 13 14:13:00.205171 ignition[1225]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Dec 13 14:13:00.207737 ignition[1225]: INFO : PUT result: OK Dec 13 14:13:00.212118 ignition[1225]: kargs: kargs passed Dec 13 14:13:00.212220 ignition[1225]: Ignition finished successfully Dec 13 14:13:00.214695 systemd[1]: Finished ignition-kargs.service. Dec 13 14:13:00.216000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-kargs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:00.226695 kernel: audit: type=1130 audit(1734099180.216:31): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-kargs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:00.227756 systemd[1]: Starting ignition-disks.service... Dec 13 14:13:00.243573 ignition[1231]: Ignition 2.14.0 Dec 13 14:13:00.243603 ignition[1231]: Stage: disks Dec 13 14:13:00.243935 ignition[1231]: reading system config file "/usr/lib/ignition/base.d/base.ign" Dec 13 14:13:00.243998 ignition[1231]: parsing config with SHA512: 6629d8e825d60c9c9d4629d8547ef9a0b839d6b01b7f61a481a1f23308c924b8b0bbf10cae7f7fe3bcaf88b23d1a81baa7771c3670728d4d2a1e665216a1de7b Dec 13 14:13:00.260780 ignition[1231]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Dec 13 14:13:00.263323 ignition[1231]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Dec 13 14:13:00.268745 ignition[1231]: INFO : PUT result: OK Dec 13 14:13:00.268617 ignition[1231]: disks: disks passed Dec 13 14:13:00.268735 ignition[1231]: Ignition finished successfully Dec 13 14:13:00.281044 systemd[1]: Finished ignition-disks.service. Dec 13 14:13:00.286095 systemd[1]: Reached target initrd-root-device.target. Dec 13 14:13:00.289187 systemd[1]: Reached target local-fs-pre.target. Dec 13 14:13:00.290759 systemd[1]: Reached target local-fs.target. Dec 13 14:13:00.294881 systemd[1]: Reached target sysinit.target. Dec 13 14:13:00.297472 systemd[1]: Reached target basic.target. Dec 13 14:13:00.284000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-disks comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:00.301548 systemd[1]: Starting systemd-fsck-root.service... Dec 13 14:13:00.310321 kernel: audit: type=1130 audit(1734099180.284:32): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-disks comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:00.350065 systemd-fsck[1239]: ROOT: clean, 621/553520 files, 56020/553472 blocks Dec 13 14:13:00.355362 systemd[1]: Finished systemd-fsck-root.service. Dec 13 14:13:00.355000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:00.358510 systemd[1]: Mounting sysroot.mount... Dec 13 14:13:00.367723 kernel: audit: type=1130 audit(1734099180.355:33): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:00.387712 kernel: EXT4-fs (nvme0n1p9): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none. Dec 13 14:13:00.389993 systemd[1]: Mounted sysroot.mount. Dec 13 14:13:00.392865 systemd[1]: Reached target initrd-root-fs.target. Dec 13 14:13:00.407043 systemd[1]: Mounting sysroot-usr.mount... Dec 13 14:13:00.410784 systemd[1]: flatcar-metadata-hostname.service was skipped because no trigger condition checks were met. Dec 13 14:13:00.411968 systemd[1]: ignition-remount-sysroot.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Dec 13 14:13:00.412023 systemd[1]: Reached target ignition-diskful.target. Dec 13 14:13:00.425508 systemd[1]: Mounted sysroot-usr.mount. Dec 13 14:13:00.432387 systemd[1]: Mounting sysroot-usr-share-oem.mount... Dec 13 14:13:00.437573 systemd[1]: Starting initrd-setup-root.service... Dec 13 14:13:00.455530 initrd-setup-root[1261]: cut: /sysroot/etc/passwd: No such file or directory Dec 13 14:13:00.463703 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/nvme0n1p6 scanned by mount (1256) Dec 13 14:13:00.468569 initrd-setup-root[1269]: cut: /sysroot/etc/group: No such file or directory Dec 13 14:13:00.474958 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Dec 13 14:13:00.474993 kernel: BTRFS info (device nvme0n1p6): using free space tree Dec 13 14:13:00.475017 kernel: BTRFS info (device nvme0n1p6): has skinny extents Dec 13 14:13:00.482030 initrd-setup-root[1293]: cut: /sysroot/etc/shadow: No such file or directory Dec 13 14:13:00.491576 initrd-setup-root[1301]: cut: /sysroot/etc/gshadow: No such file or directory Dec 13 14:13:00.513717 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Dec 13 14:13:00.525271 systemd[1]: Mounted sysroot-usr-share-oem.mount. Dec 13 14:13:00.644294 systemd[1]: Finished initrd-setup-root.service. Dec 13 14:13:00.642000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:00.656100 systemd[1]: Starting ignition-mount.service... Dec 13 14:13:00.659844 kernel: audit: type=1130 audit(1734099180.642:34): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:00.659751 systemd[1]: Starting sysroot-boot.service... Dec 13 14:13:00.672487 systemd[1]: sysusr-usr-share-oem.mount: Deactivated successfully. Dec 13 14:13:00.672699 systemd[1]: sysroot-usr-share-oem.mount: Deactivated successfully. Dec 13 14:13:00.698487 ignition[1321]: INFO : Ignition 2.14.0 Dec 13 14:13:00.700464 ignition[1321]: INFO : Stage: mount Dec 13 14:13:00.702384 ignition[1321]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign" Dec 13 14:13:00.704899 ignition[1321]: DEBUG : parsing config with SHA512: 6629d8e825d60c9c9d4629d8547ef9a0b839d6b01b7f61a481a1f23308c924b8b0bbf10cae7f7fe3bcaf88b23d1a81baa7771c3670728d4d2a1e665216a1de7b Dec 13 14:13:00.722900 systemd[1]: Finished sysroot-boot.service. Dec 13 14:13:00.724000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=sysroot-boot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:00.733712 kernel: audit: type=1130 audit(1734099180.724:35): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=sysroot-boot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:00.733915 ignition[1321]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Dec 13 14:13:00.736472 ignition[1321]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Dec 13 14:13:00.739553 ignition[1321]: INFO : PUT result: OK Dec 13 14:13:00.743920 ignition[1321]: INFO : mount: mount passed Dec 13 14:13:00.745855 ignition[1321]: INFO : Ignition finished successfully Dec 13 14:13:00.747896 systemd[1]: Finished ignition-mount.service. Dec 13 14:13:00.749000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:00.752046 systemd[1]: Starting ignition-files.service... Dec 13 14:13:00.760544 kernel: audit: type=1130 audit(1734099180.749:36): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:00.768932 systemd[1]: Mounting sysroot-usr-share-oem.mount... Dec 13 14:13:00.791720 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/nvme0n1p6 scanned by mount (1331) Dec 13 14:13:00.797709 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Dec 13 14:13:00.797755 kernel: BTRFS info (device nvme0n1p6): using free space tree Dec 13 14:13:00.797780 kernel: BTRFS info (device nvme0n1p6): has skinny extents Dec 13 14:13:00.828696 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Dec 13 14:13:00.834875 systemd[1]: Mounted sysroot-usr-share-oem.mount. Dec 13 14:13:00.854139 ignition[1350]: INFO : Ignition 2.14.0 Dec 13 14:13:00.854139 ignition[1350]: INFO : Stage: files Dec 13 14:13:00.858069 ignition[1350]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign" Dec 13 14:13:00.858069 ignition[1350]: DEBUG : parsing config with SHA512: 6629d8e825d60c9c9d4629d8547ef9a0b839d6b01b7f61a481a1f23308c924b8b0bbf10cae7f7fe3bcaf88b23d1a81baa7771c3670728d4d2a1e665216a1de7b Dec 13 14:13:00.875819 ignition[1350]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Dec 13 14:13:00.878236 systemd-networkd[1195]: eth0: Gained IPv6LL Dec 13 14:13:00.880403 ignition[1350]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Dec 13 14:13:00.883295 ignition[1350]: INFO : PUT result: OK Dec 13 14:13:00.887697 ignition[1350]: DEBUG : files: compiled without relabeling support, skipping Dec 13 14:13:00.893002 ignition[1350]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Dec 13 14:13:00.893002 ignition[1350]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Dec 13 14:13:00.906310 ignition[1350]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Dec 13 14:13:00.909242 ignition[1350]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Dec 13 14:13:00.913009 unknown[1350]: wrote ssh authorized keys file for user: core Dec 13 14:13:00.915214 ignition[1350]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Dec 13 14:13:00.926073 ignition[1350]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/eks/bootstrap.sh" Dec 13 14:13:00.929399 ignition[1350]: INFO : oem config not found in "/usr/share/oem", looking on oem partition Dec 13 14:13:00.950539 ignition[1350]: INFO : op(1): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem2726828252" Dec 13 14:13:00.958001 kernel: BTRFS info: devid 1 device path /dev/nvme0n1p6 changed to /dev/disk/by-label/OEM scanned by ignition (1352) Dec 13 14:13:00.958047 ignition[1350]: CRITICAL : op(1): [failed] mounting "/dev/disk/by-label/OEM" at "/mnt/oem2726828252": device or resource busy Dec 13 14:13:00.958047 ignition[1350]: ERROR : failed to mount ext4 device "/dev/disk/by-label/OEM" at "/mnt/oem2726828252", trying btrfs: device or resource busy Dec 13 14:13:00.958047 ignition[1350]: INFO : op(2): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem2726828252" Dec 13 14:13:00.966930 ignition[1350]: INFO : op(2): [finished] mounting "/dev/disk/by-label/OEM" at "/mnt/oem2726828252" Dec 13 14:13:00.973086 ignition[1350]: INFO : op(3): [started] unmounting "/mnt/oem2726828252" Dec 13 14:13:00.980934 ignition[1350]: INFO : op(3): [finished] unmounting "/mnt/oem2726828252" Dec 13 14:13:00.980934 ignition[1350]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/eks/bootstrap.sh" Dec 13 14:13:00.986555 ignition[1350]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Dec 13 14:13:00.986555 ignition[1350]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Dec 13 14:13:00.986555 ignition[1350]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing file "/sysroot/etc/amazon/ssm/amazon-ssm-agent.json" Dec 13 14:13:00.986555 ignition[1350]: INFO : oem config not found in "/usr/share/oem", looking on oem partition Dec 13 14:13:01.010862 ignition[1350]: INFO : op(4): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem2413411830" Dec 13 14:13:01.010862 ignition[1350]: CRITICAL : op(4): [failed] mounting "/dev/disk/by-label/OEM" at "/mnt/oem2413411830": device or resource busy Dec 13 14:13:01.010862 ignition[1350]: ERROR : failed to mount ext4 device "/dev/disk/by-label/OEM" at "/mnt/oem2413411830", trying btrfs: device or resource busy Dec 13 14:13:01.010862 ignition[1350]: INFO : op(5): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem2413411830" Dec 13 14:13:01.010862 ignition[1350]: INFO : op(5): [finished] mounting "/dev/disk/by-label/OEM" at "/mnt/oem2413411830" Dec 13 14:13:01.010862 ignition[1350]: INFO : op(6): [started] unmounting "/mnt/oem2413411830" Dec 13 14:13:01.010862 ignition[1350]: INFO : op(6): [finished] unmounting "/mnt/oem2413411830" Dec 13 14:13:01.010862 ignition[1350]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing file "/sysroot/etc/amazon/ssm/amazon-ssm-agent.json" Dec 13 14:13:01.010862 ignition[1350]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/etc/amazon/ssm/seelog.xml" Dec 13 14:13:01.010862 ignition[1350]: INFO : oem config not found in "/usr/share/oem", looking on oem partition Dec 13 14:13:01.042345 ignition[1350]: INFO : op(7): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem2075665964" Dec 13 14:13:01.042345 ignition[1350]: CRITICAL : op(7): [failed] mounting "/dev/disk/by-label/OEM" at "/mnt/oem2075665964": device or resource busy Dec 13 14:13:01.042345 ignition[1350]: ERROR : failed to mount ext4 device "/dev/disk/by-label/OEM" at "/mnt/oem2075665964", trying btrfs: device or resource busy Dec 13 14:13:01.042345 ignition[1350]: INFO : op(8): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem2075665964" Dec 13 14:13:01.042345 ignition[1350]: INFO : op(8): [finished] mounting "/dev/disk/by-label/OEM" at "/mnt/oem2075665964" Dec 13 14:13:01.042345 ignition[1350]: INFO : op(9): [started] unmounting "/mnt/oem2075665964" Dec 13 14:13:01.061652 ignition[1350]: INFO : op(9): [finished] unmounting "/mnt/oem2075665964" Dec 13 14:13:01.061652 ignition[1350]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/etc/amazon/ssm/seelog.xml" Dec 13 14:13:01.061652 ignition[1350]: INFO : files: createFilesystemsFiles: createFiles: op(7): [started] writing file "/sysroot/etc/systemd/system/nvidia.service" Dec 13 14:13:01.061652 ignition[1350]: INFO : oem config not found in "/usr/share/oem", looking on oem partition Dec 13 14:13:01.078328 ignition[1350]: INFO : op(a): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem2797960827" Dec 13 14:13:01.078328 ignition[1350]: CRITICAL : op(a): [failed] mounting "/dev/disk/by-label/OEM" at "/mnt/oem2797960827": device or resource busy Dec 13 14:13:01.078328 ignition[1350]: ERROR : failed to mount ext4 device "/dev/disk/by-label/OEM" at "/mnt/oem2797960827", trying btrfs: device or resource busy Dec 13 14:13:01.078328 ignition[1350]: INFO : op(b): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem2797960827" Dec 13 14:13:01.078328 ignition[1350]: INFO : op(b): [finished] mounting "/dev/disk/by-label/OEM" at "/mnt/oem2797960827" Dec 13 14:13:01.078328 ignition[1350]: INFO : op(c): [started] unmounting "/mnt/oem2797960827" Dec 13 14:13:01.078328 ignition[1350]: INFO : op(c): [finished] unmounting "/mnt/oem2797960827" Dec 13 14:13:01.096097 ignition[1350]: INFO : files: createFilesystemsFiles: createFiles: op(7): [finished] writing file "/sysroot/etc/systemd/system/nvidia.service" Dec 13 14:13:01.096097 ignition[1350]: INFO : files: op(8): [started] processing unit "coreos-metadata-sshkeys@.service" Dec 13 14:13:01.096097 ignition[1350]: INFO : files: op(8): [finished] processing unit "coreos-metadata-sshkeys@.service" Dec 13 14:13:01.096097 ignition[1350]: INFO : files: op(9): [started] processing unit "amazon-ssm-agent.service" Dec 13 14:13:01.096097 ignition[1350]: INFO : files: op(9): op(a): [started] writing unit "amazon-ssm-agent.service" at "/sysroot/etc/systemd/system/amazon-ssm-agent.service" Dec 13 14:13:01.096097 ignition[1350]: INFO : files: op(9): op(a): [finished] writing unit "amazon-ssm-agent.service" at "/sysroot/etc/systemd/system/amazon-ssm-agent.service" Dec 13 14:13:01.096097 ignition[1350]: INFO : files: op(9): [finished] processing unit "amazon-ssm-agent.service" Dec 13 14:13:01.096097 ignition[1350]: INFO : files: op(b): [started] processing unit "nvidia.service" Dec 13 14:13:01.096097 ignition[1350]: INFO : files: op(b): [finished] processing unit "nvidia.service" Dec 13 14:13:01.120509 ignition[1350]: INFO : files: op(c): [started] processing unit "etcd-member.service" Dec 13 14:13:01.120509 ignition[1350]: INFO : files: op(c): op(d): [started] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" Dec 13 14:13:01.120509 ignition[1350]: INFO : files: op(c): op(d): [finished] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" Dec 13 14:13:01.120509 ignition[1350]: INFO : files: op(c): [finished] processing unit "etcd-member.service" Dec 13 14:13:01.120509 ignition[1350]: INFO : files: op(e): [started] setting preset to enabled for "coreos-metadata-sshkeys@.service " Dec 13 14:13:01.120509 ignition[1350]: INFO : files: op(e): [finished] setting preset to enabled for "coreos-metadata-sshkeys@.service " Dec 13 14:13:01.120509 ignition[1350]: INFO : files: op(f): [started] setting preset to enabled for "amazon-ssm-agent.service" Dec 13 14:13:01.120509 ignition[1350]: INFO : files: op(f): [finished] setting preset to enabled for "amazon-ssm-agent.service" Dec 13 14:13:01.120509 ignition[1350]: INFO : files: op(10): [started] setting preset to enabled for "nvidia.service" Dec 13 14:13:01.147743 ignition[1350]: INFO : files: op(10): [finished] setting preset to enabled for "nvidia.service" Dec 13 14:13:01.147743 ignition[1350]: INFO : files: op(11): [started] setting preset to enabled for "etcd-member.service" Dec 13 14:13:01.147743 ignition[1350]: INFO : files: op(11): [finished] setting preset to enabled for "etcd-member.service" Dec 13 14:13:01.159691 ignition[1350]: INFO : files: createResultFile: createFiles: op(12): [started] writing file "/sysroot/etc/.ignition-result.json" Dec 13 14:13:01.159691 ignition[1350]: INFO : files: createResultFile: createFiles: op(12): [finished] writing file "/sysroot/etc/.ignition-result.json" Dec 13 14:13:01.159691 ignition[1350]: INFO : files: files passed Dec 13 14:13:01.159691 ignition[1350]: INFO : Ignition finished successfully Dec 13 14:13:01.170303 systemd[1]: Finished ignition-files.service. Dec 13 14:13:01.172000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.180733 kernel: audit: type=1130 audit(1734099181.172:37): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.187195 systemd[1]: Starting initrd-setup-root-after-ignition.service... Dec 13 14:13:01.191281 systemd[1]: torcx-profile-populate.service was skipped because of an unmet condition check (ConditionPathExists=/sysroot/etc/torcx/next-profile). Dec 13 14:13:01.198439 systemd[1]: Starting ignition-quench.service... Dec 13 14:13:01.205701 systemd[1]: ignition-quench.service: Deactivated successfully. Dec 13 14:13:01.206000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.216739 kernel: audit: type=1130 audit(1734099181.206:38): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.206000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.205948 systemd[1]: Finished ignition-quench.service. Dec 13 14:13:01.220026 initrd-setup-root-after-ignition[1375]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Dec 13 14:13:01.224401 systemd[1]: Finished initrd-setup-root-after-ignition.service. Dec 13 14:13:01.222000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root-after-ignition comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.227515 systemd[1]: Reached target ignition-complete.target. Dec 13 14:13:01.233087 systemd[1]: Starting initrd-parse-etc.service... Dec 13 14:13:01.262286 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Dec 13 14:13:01.264136 systemd[1]: Finished initrd-parse-etc.service. Dec 13 14:13:01.264000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.266000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.267482 systemd[1]: Reached target initrd-fs.target. Dec 13 14:13:01.270308 systemd[1]: Reached target initrd.target. Dec 13 14:13:01.273004 systemd[1]: dracut-mount.service was skipped because no trigger condition checks were met. Dec 13 14:13:01.276637 systemd[1]: Starting dracut-pre-pivot.service... Dec 13 14:13:01.299539 systemd[1]: Finished dracut-pre-pivot.service. Dec 13 14:13:01.300000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-pivot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.302767 systemd[1]: Starting initrd-cleanup.service... Dec 13 14:13:01.324372 systemd[1]: Stopped target nss-lookup.target. Dec 13 14:13:01.327657 systemd[1]: Stopped target remote-cryptsetup.target. Dec 13 14:13:01.331187 systemd[1]: Stopped target timers.target. Dec 13 14:13:01.334177 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Dec 13 14:13:01.336214 systemd[1]: Stopped dracut-pre-pivot.service. Dec 13 14:13:01.338000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-pivot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.339773 systemd[1]: Stopped target initrd.target. Dec 13 14:13:01.342807 systemd[1]: Stopped target basic.target. Dec 13 14:13:01.345758 systemd[1]: Stopped target ignition-complete.target. Dec 13 14:13:01.349173 systemd[1]: Stopped target ignition-diskful.target. Dec 13 14:13:01.352513 systemd[1]: Stopped target initrd-root-device.target. Dec 13 14:13:01.355976 systemd[1]: Stopped target remote-fs.target. Dec 13 14:13:01.359008 systemd[1]: Stopped target remote-fs-pre.target. Dec 13 14:13:01.362260 systemd[1]: Stopped target sysinit.target. Dec 13 14:13:01.364992 systemd[1]: Stopped target local-fs.target. Dec 13 14:13:01.367790 systemd[1]: Stopped target local-fs-pre.target. Dec 13 14:13:01.370848 systemd[1]: Stopped target swap.target. Dec 13 14:13:01.373513 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Dec 13 14:13:01.375457 systemd[1]: Stopped dracut-pre-mount.service. Dec 13 14:13:01.377000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.378686 systemd[1]: Stopped target cryptsetup.target. Dec 13 14:13:01.381710 systemd[1]: dracut-initqueue.service: Deactivated successfully. Dec 13 14:13:01.383635 systemd[1]: Stopped dracut-initqueue.service. Dec 13 14:13:01.385000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-initqueue comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.386992 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Dec 13 14:13:01.389336 systemd[1]: Stopped initrd-setup-root-after-ignition.service. Dec 13 14:13:01.391000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root-after-ignition comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.393092 systemd[1]: ignition-files.service: Deactivated successfully. Dec 13 14:13:01.395018 systemd[1]: Stopped ignition-files.service. Dec 13 14:13:01.396000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.399612 systemd[1]: Stopping ignition-mount.service... Dec 13 14:13:01.403184 systemd[1]: Stopping iscsid.service... Dec 13 14:13:01.418436 iscsid[1200]: iscsid shutting down. Dec 13 14:13:01.417000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.410979 systemd[1]: Stopping sysroot-boot.service... Dec 13 14:13:01.412514 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Dec 13 14:13:01.414722 systemd[1]: Stopped systemd-udev-trigger.service. Dec 13 14:13:01.432860 ignition[1388]: INFO : Ignition 2.14.0 Dec 13 14:13:01.432860 ignition[1388]: INFO : Stage: umount Dec 13 14:13:01.432860 ignition[1388]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign" Dec 13 14:13:01.432860 ignition[1388]: DEBUG : parsing config with SHA512: 6629d8e825d60c9c9d4629d8547ef9a0b839d6b01b7f61a481a1f23308c924b8b0bbf10cae7f7fe3bcaf88b23d1a81baa7771c3670728d4d2a1e665216a1de7b Dec 13 14:13:01.419032 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Dec 13 14:13:01.420922 systemd[1]: Stopped dracut-pre-trigger.service. Dec 13 14:13:01.462000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.472128 systemd[1]: iscsid.service: Deactivated successfully. Dec 13 14:13:01.472594 systemd[1]: Stopped iscsid.service. Dec 13 14:13:01.477907 ignition[1388]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Dec 13 14:13:01.477907 ignition[1388]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Dec 13 14:13:01.483639 ignition[1388]: INFO : PUT result: OK Dec 13 14:13:01.481000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.488824 systemd[1]: Stopping iscsiuio.service... Dec 13 14:13:01.497018 ignition[1388]: INFO : umount: umount passed Dec 13 14:13:01.497018 ignition[1388]: INFO : Ignition finished successfully Dec 13 14:13:01.500714 systemd[1]: iscsiuio.service: Deactivated successfully. Dec 13 14:13:01.501000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsiuio comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.500973 systemd[1]: Stopped iscsiuio.service. Dec 13 14:13:01.505698 systemd[1]: initrd-cleanup.service: Deactivated successfully. Dec 13 14:13:01.507428 systemd[1]: Finished initrd-cleanup.service. Dec 13 14:13:01.507000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.507000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.510688 systemd[1]: ignition-mount.service: Deactivated successfully. Dec 13 14:13:01.511000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.513000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-disks comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.515000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-kargs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.516000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.510881 systemd[1]: Stopped ignition-mount.service. Dec 13 14:13:01.521000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch-offline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.512785 systemd[1]: ignition-disks.service: Deactivated successfully. Dec 13 14:13:01.512878 systemd[1]: Stopped ignition-disks.service. Dec 13 14:13:01.514504 systemd[1]: ignition-kargs.service: Deactivated successfully. Dec 13 14:13:01.514583 systemd[1]: Stopped ignition-kargs.service. Dec 13 14:13:01.516183 systemd[1]: ignition-fetch.service: Deactivated successfully. Dec 13 14:13:01.516300 systemd[1]: Stopped ignition-fetch.service. Dec 13 14:13:01.519401 systemd[1]: Stopped target network.target. Dec 13 14:13:01.520840 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Dec 13 14:13:01.520924 systemd[1]: Stopped ignition-fetch-offline.service. Dec 13 14:13:01.522884 systemd[1]: Stopped target paths.target. Dec 13 14:13:01.526393 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Dec 13 14:13:01.531398 systemd[1]: Stopped systemd-ask-password-console.path. Dec 13 14:13:01.535939 systemd[1]: Stopped target slices.target. Dec 13 14:13:01.544783 systemd[1]: Stopped target sockets.target. Dec 13 14:13:01.555010 systemd[1]: iscsid.socket: Deactivated successfully. Dec 13 14:13:01.555104 systemd[1]: Closed iscsid.socket. Dec 13 14:13:01.571003 systemd[1]: iscsiuio.socket: Deactivated successfully. Dec 13 14:13:01.571074 systemd[1]: Closed iscsiuio.socket. Dec 13 14:13:01.574778 systemd[1]: ignition-setup.service: Deactivated successfully. Dec 13 14:13:01.574876 systemd[1]: Stopped ignition-setup.service. Dec 13 14:13:01.576000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.579569 systemd[1]: Stopping systemd-networkd.service... Dec 13 14:13:01.581305 systemd[1]: Stopping systemd-resolved.service... Dec 13 14:13:01.586000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=sysroot-boot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.585657 systemd[1]: sysroot-boot.mount: Deactivated successfully. Dec 13 14:13:01.586519 systemd[1]: sysroot-boot.service: Deactivated successfully. Dec 13 14:13:01.586706 systemd[1]: Stopped sysroot-boot.service. Dec 13 14:13:01.590540 systemd[1]: initrd-setup-root.service: Deactivated successfully. Dec 13 14:13:01.590632 systemd[1]: Stopped initrd-setup-root.service. Dec 13 14:13:01.597000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.599723 systemd-networkd[1195]: eth0: DHCPv6 lease lost Dec 13 14:13:01.602790 systemd[1]: systemd-networkd.service: Deactivated successfully. Dec 13 14:13:01.605000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.603030 systemd[1]: Stopped systemd-networkd.service. Dec 13 14:13:01.609349 systemd[1]: systemd-resolved.service: Deactivated successfully. Dec 13 14:13:01.610000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.609552 systemd[1]: Stopped systemd-resolved.service. Dec 13 14:13:01.615206 systemd[1]: systemd-networkd.socket: Deactivated successfully. Dec 13 14:13:01.616000 audit: BPF prog-id=6 op=UNLOAD Dec 13 14:13:01.615277 systemd[1]: Closed systemd-networkd.socket. Dec 13 14:13:01.625000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=parse-ip-for-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.619280 systemd[1]: Stopping network-cleanup.service... Dec 13 14:13:01.623628 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Dec 13 14:13:01.628000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.624404 systemd[1]: Stopped parse-ip-for-networkd.service. Dec 13 14:13:01.630000 audit: BPF prog-id=9 op=UNLOAD Dec 13 14:13:01.632000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.626787 systemd[1]: systemd-sysctl.service: Deactivated successfully. Dec 13 14:13:01.627328 systemd[1]: Stopped systemd-sysctl.service. Dec 13 14:13:01.631495 systemd[1]: systemd-modules-load.service: Deactivated successfully. Dec 13 14:13:01.655000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=network-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.631626 systemd[1]: Stopped systemd-modules-load.service. Dec 13 14:13:01.641325 systemd[1]: Stopping systemd-udevd.service... Dec 13 14:13:01.645278 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Dec 13 14:13:01.652787 systemd[1]: network-cleanup.service: Deactivated successfully. Dec 13 14:13:01.653031 systemd[1]: Stopped network-cleanup.service. Dec 13 14:13:01.672165 systemd[1]: systemd-udevd.service: Deactivated successfully. Dec 13 14:13:01.672984 systemd[1]: Stopped systemd-udevd.service. Dec 13 14:13:01.674000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.677141 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Dec 13 14:13:01.677348 systemd[1]: Closed systemd-udevd-control.socket. Dec 13 14:13:01.681887 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Dec 13 14:13:01.682067 systemd[1]: Closed systemd-udevd-kernel.socket. Dec 13 14:13:01.687076 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Dec 13 14:13:01.687179 systemd[1]: Stopped dracut-pre-udev.service. Dec 13 14:13:01.689000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.692125 systemd[1]: dracut-cmdline.service: Deactivated successfully. Dec 13 14:13:01.692221 systemd[1]: Stopped dracut-cmdline.service. Dec 13 14:13:01.694000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.696464 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Dec 13 14:13:01.696553 systemd[1]: Stopped dracut-cmdline-ask.service. Dec 13 14:13:01.698000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.702486 systemd[1]: Starting initrd-udevadm-cleanup-db.service... Dec 13 14:13:01.722000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.718790 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Dec 13 14:13:01.726000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.727000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.718915 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service. Dec 13 14:13:01.724815 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Dec 13 14:13:01.733000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-udevadm-cleanup-db comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.733000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-udevadm-cleanup-db comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.724902 systemd[1]: Stopped kmod-static-nodes.service. Dec 13 14:13:01.727939 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Dec 13 14:13:01.728019 systemd[1]: Stopped systemd-vconsole-setup.service. Dec 13 14:13:01.732110 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Dec 13 14:13:01.733224 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Dec 13 14:13:01.733406 systemd[1]: Finished initrd-udevadm-cleanup-db.service. Dec 13 14:13:01.735964 systemd[1]: Reached target initrd-switch-root.target. Dec 13 14:13:01.750916 systemd[1]: Starting initrd-switch-root.service... Dec 13 14:13:01.770568 systemd[1]: Switching root. Dec 13 14:13:01.796711 systemd-journald[309]: Journal stopped Dec 13 14:13:06.783840 systemd-journald[309]: Received SIGTERM from PID 1 (systemd). Dec 13 14:13:06.784011 kernel: SELinux: Class mctp_socket not defined in policy. Dec 13 14:13:06.784055 kernel: SELinux: Class anon_inode not defined in policy. Dec 13 14:13:06.784091 kernel: SELinux: the above unknown classes and permissions will be allowed Dec 13 14:13:06.784125 kernel: SELinux: policy capability network_peer_controls=1 Dec 13 14:13:06.784173 kernel: SELinux: policy capability open_perms=1 Dec 13 14:13:06.784208 kernel: SELinux: policy capability extended_socket_class=1 Dec 13 14:13:06.784241 kernel: SELinux: policy capability always_check_network=0 Dec 13 14:13:06.784271 kernel: SELinux: policy capability cgroup_seclabel=1 Dec 13 14:13:06.784304 kernel: SELinux: policy capability nnp_nosuid_transition=1 Dec 13 14:13:06.784334 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Dec 13 14:13:06.784373 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Dec 13 14:13:06.784411 systemd[1]: Successfully loaded SELinux policy in 82.138ms. Dec 13 14:13:06.784471 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 20.377ms. Dec 13 14:13:06.784509 systemd[1]: systemd 252 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) Dec 13 14:13:06.784558 systemd[1]: Detected virtualization amazon. Dec 13 14:13:06.784595 systemd[1]: Detected architecture arm64. Dec 13 14:13:06.784626 systemd[1]: Detected first boot. Dec 13 14:13:06.784677 systemd[1]: Initializing machine ID from VM UUID. Dec 13 14:13:06.784719 kernel: SELinux: Context system_u:object_r:container_file_t:s0:c1022,c1023 is not valid (left unmapped). Dec 13 14:13:06.784755 systemd[1]: Populated /etc with preset unit settings. Dec 13 14:13:06.784788 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Dec 13 14:13:06.784829 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Dec 13 14:13:06.784874 systemd[1]: /run/systemd/system/docker.socket:8: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Dec 13 14:13:06.784907 kernel: kauditd_printk_skb: 57 callbacks suppressed Dec 13 14:13:06.784937 kernel: audit: type=1334 audit(1734099186.400:89): prog-id=12 op=LOAD Dec 13 14:13:06.784965 kernel: audit: type=1334 audit(1734099186.400:90): prog-id=3 op=UNLOAD Dec 13 14:13:06.784994 kernel: audit: type=1334 audit(1734099186.401:91): prog-id=13 op=LOAD Dec 13 14:13:06.785027 kernel: audit: type=1334 audit(1734099186.401:92): prog-id=14 op=LOAD Dec 13 14:13:06.785059 systemd[1]: initrd-switch-root.service: Deactivated successfully. Dec 13 14:13:06.785088 kernel: audit: type=1334 audit(1734099186.401:93): prog-id=4 op=UNLOAD Dec 13 14:13:06.785118 systemd[1]: Stopped initrd-switch-root.service. Dec 13 14:13:06.785148 kernel: audit: type=1334 audit(1734099186.401:94): prog-id=5 op=UNLOAD Dec 13 14:13:06.785175 kernel: audit: type=1334 audit(1734099186.406:95): prog-id=15 op=LOAD Dec 13 14:13:06.785208 kernel: audit: type=1334 audit(1734099186.406:96): prog-id=12 op=UNLOAD Dec 13 14:13:06.785237 kernel: audit: type=1334 audit(1734099186.408:97): prog-id=16 op=LOAD Dec 13 14:13:06.785264 kernel: audit: type=1334 audit(1734099186.411:98): prog-id=17 op=LOAD Dec 13 14:13:06.785297 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Dec 13 14:13:06.785327 systemd[1]: Created slice system-addon\x2dconfig.slice. Dec 13 14:13:06.785362 systemd[1]: Created slice system-addon\x2drun.slice. Dec 13 14:13:06.785398 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice. Dec 13 14:13:06.785428 systemd[1]: Created slice system-getty.slice. Dec 13 14:13:06.785460 systemd[1]: Created slice system-modprobe.slice. Dec 13 14:13:06.785493 systemd[1]: Created slice system-serial\x2dgetty.slice. Dec 13 14:13:06.785524 systemd[1]: Created slice system-system\x2dcloudinit.slice. Dec 13 14:13:06.785558 systemd[1]: Created slice system-systemd\x2dfsck.slice. Dec 13 14:13:06.785590 systemd[1]: Created slice user.slice. Dec 13 14:13:06.785620 systemd[1]: Started systemd-ask-password-console.path. Dec 13 14:13:06.785650 systemd[1]: Started systemd-ask-password-wall.path. Dec 13 14:13:06.785700 systemd[1]: Set up automount boot.automount. Dec 13 14:13:06.785735 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount. Dec 13 14:13:06.785767 systemd[1]: Stopped target initrd-switch-root.target. Dec 13 14:13:06.785797 systemd[1]: Stopped target initrd-fs.target. Dec 13 14:13:06.789493 systemd[1]: Stopped target initrd-root-fs.target. Dec 13 14:13:06.789545 systemd[1]: Reached target integritysetup.target. Dec 13 14:13:06.789579 systemd[1]: Reached target remote-cryptsetup.target. Dec 13 14:13:06.789612 systemd[1]: Reached target remote-fs.target. Dec 13 14:13:06.789643 systemd[1]: Reached target slices.target. Dec 13 14:13:06.789701 systemd[1]: Reached target swap.target. Dec 13 14:13:06.789735 systemd[1]: Reached target torcx.target. Dec 13 14:13:06.789766 systemd[1]: Reached target veritysetup.target. Dec 13 14:13:06.789803 systemd[1]: Listening on systemd-coredump.socket. Dec 13 14:13:06.789922 systemd[1]: Listening on systemd-initctl.socket. Dec 13 14:13:06.790528 systemd[1]: Listening on systemd-networkd.socket. Dec 13 14:13:06.791232 systemd[1]: Listening on systemd-udevd-control.socket. Dec 13 14:13:06.791269 systemd[1]: Listening on systemd-udevd-kernel.socket. Dec 13 14:13:06.791299 systemd[1]: Listening on systemd-userdbd.socket. Dec 13 14:13:06.791332 systemd[1]: Mounting dev-hugepages.mount... Dec 13 14:13:06.791363 systemd[1]: Mounting dev-mqueue.mount... Dec 13 14:13:06.791393 systemd[1]: Mounting media.mount... Dec 13 14:13:06.791422 systemd[1]: Mounting sys-kernel-debug.mount... Dec 13 14:13:06.791454 systemd[1]: Mounting sys-kernel-tracing.mount... Dec 13 14:13:06.791483 systemd[1]: Mounting tmp.mount... Dec 13 14:13:06.791519 systemd[1]: Starting flatcar-tmpfiles.service... Dec 13 14:13:06.791550 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Dec 13 14:13:06.791583 systemd[1]: Starting kmod-static-nodes.service... Dec 13 14:13:06.791615 systemd[1]: Starting modprobe@configfs.service... Dec 13 14:13:06.791645 systemd[1]: Starting modprobe@dm_mod.service... Dec 13 14:13:06.791704 systemd[1]: Starting modprobe@drm.service... Dec 13 14:13:06.791736 systemd[1]: Starting modprobe@efi_pstore.service... Dec 13 14:13:06.791766 systemd[1]: Starting modprobe@fuse.service... Dec 13 14:13:06.791799 systemd[1]: Starting modprobe@loop.service... Dec 13 14:13:06.791895 systemd[1]: setup-nsswitch.service was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Dec 13 14:13:06.791940 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Dec 13 14:13:06.791974 systemd[1]: Stopped systemd-fsck-root.service. Dec 13 14:13:06.792007 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Dec 13 14:13:06.792038 systemd[1]: Stopped systemd-fsck-usr.service. Dec 13 14:13:06.792067 systemd[1]: Stopped systemd-journald.service. Dec 13 14:13:06.792098 kernel: loop: module loaded Dec 13 14:13:06.792128 systemd[1]: Starting systemd-journald.service... Dec 13 14:13:06.792158 systemd[1]: Starting systemd-modules-load.service... Dec 13 14:13:06.792192 systemd[1]: Starting systemd-network-generator.service... Dec 13 14:13:06.792223 systemd[1]: Starting systemd-remount-fs.service... Dec 13 14:13:06.792253 systemd[1]: Starting systemd-udev-trigger.service... Dec 13 14:13:06.792285 systemd[1]: verity-setup.service: Deactivated successfully. Dec 13 14:13:06.792319 systemd[1]: Stopped verity-setup.service. Dec 13 14:13:06.792348 kernel: fuse: init (API version 7.34) Dec 13 14:13:06.792377 systemd[1]: Mounted dev-hugepages.mount. Dec 13 14:13:06.792415 systemd[1]: Mounted dev-mqueue.mount. Dec 13 14:13:06.792449 systemd[1]: Mounted media.mount. Dec 13 14:13:06.792483 systemd[1]: Mounted sys-kernel-debug.mount. Dec 13 14:13:06.792512 systemd[1]: Mounted sys-kernel-tracing.mount. Dec 13 14:13:06.792558 systemd[1]: Mounted tmp.mount. Dec 13 14:13:06.792591 systemd[1]: Finished kmod-static-nodes.service. Dec 13 14:13:06.792625 systemd[1]: modprobe@configfs.service: Deactivated successfully. Dec 13 14:13:06.792656 systemd[1]: Finished modprobe@configfs.service. Dec 13 14:13:06.792753 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Dec 13 14:13:06.792796 systemd[1]: Finished modprobe@dm_mod.service. Dec 13 14:13:06.792827 systemd[1]: modprobe@drm.service: Deactivated successfully. Dec 13 14:13:06.792860 systemd[1]: Finished modprobe@drm.service. Dec 13 14:13:06.792890 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Dec 13 14:13:06.792922 systemd-journald[1509]: Journal started Dec 13 14:13:06.793021 systemd-journald[1509]: Runtime Journal (/run/log/journal/ec2ab2fa919ba3cc00777d8eccc0dea1) is 8.0M, max 75.4M, 67.4M free. Dec 13 14:13:02.167000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Dec 13 14:13:02.286000 audit[1]: AVC avc: denied { bpf } for pid=1 comm="systemd" capability=39 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Dec 13 14:13:02.286000 audit[1]: AVC avc: denied { perfmon } for pid=1 comm="systemd" capability=38 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Dec 13 14:13:02.286000 audit: BPF prog-id=10 op=LOAD Dec 13 14:13:02.286000 audit: BPF prog-id=10 op=UNLOAD Dec 13 14:13:02.286000 audit: BPF prog-id=11 op=LOAD Dec 13 14:13:02.286000 audit: BPF prog-id=11 op=UNLOAD Dec 13 14:13:02.433000 audit[1425]: AVC avc: denied { associate } for pid=1425 comm="torcx-generator" name="docker" dev="tmpfs" ino=2 scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 srawcon="system_u:object_r:container_file_t:s0:c1022,c1023" Dec 13 14:13:02.433000 audit[1425]: SYSCALL arch=c00000b7 syscall=5 success=yes exit=0 a0=40001458ac a1=40000c6de0 a2=40000cd0c0 a3=32 items=0 ppid=1408 pid=1425 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="torcx-generator" exe="/usr/lib/systemd/system-generators/torcx-generator" subj=system_u:system_r:kernel_t:s0 key=(null) Dec 13 14:13:02.433000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Dec 13 14:13:02.436000 audit[1425]: AVC avc: denied { associate } for pid=1425 comm="torcx-generator" name="lib" scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Dec 13 14:13:02.436000 audit[1425]: SYSCALL arch=c00000b7 syscall=34 success=yes exit=0 a0=ffffffffffffff9c a1=4000145985 a2=1ed a3=0 items=2 ppid=1408 pid=1425 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="torcx-generator" exe="/usr/lib/systemd/system-generators/torcx-generator" subj=system_u:system_r:kernel_t:s0 key=(null) Dec 13 14:13:02.436000 audit: CWD cwd="/" Dec 13 14:13:02.436000 audit: PATH item=0 name=(null) inode=2 dev=00:1c mode=040755 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:unlabeled_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Dec 13 14:13:02.436000 audit: PATH item=1 name=(null) inode=3 dev=00:1c mode=040755 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:unlabeled_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Dec 13 14:13:02.436000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Dec 13 14:13:06.400000 audit: BPF prog-id=12 op=LOAD Dec 13 14:13:06.400000 audit: BPF prog-id=3 op=UNLOAD Dec 13 14:13:06.401000 audit: BPF prog-id=13 op=LOAD Dec 13 14:13:06.401000 audit: BPF prog-id=14 op=LOAD Dec 13 14:13:06.401000 audit: BPF prog-id=4 op=UNLOAD Dec 13 14:13:06.401000 audit: BPF prog-id=5 op=UNLOAD Dec 13 14:13:06.406000 audit: BPF prog-id=15 op=LOAD Dec 13 14:13:06.406000 audit: BPF prog-id=12 op=UNLOAD Dec 13 14:13:06.408000 audit: BPF prog-id=16 op=LOAD Dec 13 14:13:06.411000 audit: BPF prog-id=17 op=LOAD Dec 13 14:13:06.411000 audit: BPF prog-id=13 op=UNLOAD Dec 13 14:13:06.411000 audit: BPF prog-id=14 op=UNLOAD Dec 13 14:13:06.411000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:06.420000 audit: BPF prog-id=15 op=UNLOAD Dec 13 14:13:06.432000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:06.432000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:06.670000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:06.677000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck-usr comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:06.682000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:06.682000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:06.805617 systemd[1]: Finished modprobe@efi_pstore.service. Dec 13 14:13:06.805729 systemd[1]: Started systemd-journald.service. Dec 13 14:13:06.684000 audit: BPF prog-id=18 op=LOAD Dec 13 14:13:06.684000 audit: BPF prog-id=19 op=LOAD Dec 13 14:13:06.684000 audit: BPF prog-id=20 op=LOAD Dec 13 14:13:06.684000 audit: BPF prog-id=16 op=UNLOAD Dec 13 14:13:06.684000 audit: BPF prog-id=17 op=UNLOAD Dec 13 14:13:06.727000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=verity-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:06.763000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:06.772000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@configfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:06.772000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@configfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:06.778000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Dec 13 14:13:06.778000 audit[1509]: SYSCALL arch=c00000b7 syscall=211 success=yes exit=60 a0=3 a1=ffffcf39e880 a2=4000 a3=1 items=0 ppid=1 pid=1509 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="systemd-journal" exe="/usr/lib/systemd/systemd-journald" subj=system_u:system_r:kernel_t:s0 key=(null) Dec 13 14:13:06.778000 audit: PROCTITLE proctitle="/usr/lib/systemd/systemd-journald" Dec 13 14:13:06.781000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:06.781000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:06.790000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:06.790000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:06.800000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:06.800000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:06.804000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:06.399836 systemd[1]: Queued start job for default target multi-user.target. Dec 13 14:13:02.430124 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2024-12-13T14:13:02Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3510.3.6 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.6 /var/lib/torcx/store]" Dec 13 14:13:06.807000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@fuse comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:06.807000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@fuse comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:06.413144 systemd[1]: systemd-journald.service: Deactivated successfully. Dec 13 14:13:02.431197 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2024-12-13T14:13:02Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Dec 13 14:13:06.806448 systemd[1]: modprobe@fuse.service: Deactivated successfully. Dec 13 14:13:02.431248 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2024-12-13T14:13:02Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Dec 13 14:13:06.810000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:06.810000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:06.806797 systemd[1]: Finished modprobe@fuse.service. Dec 13 14:13:02.431316 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2024-12-13T14:13:02Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Dec 13 14:13:06.809308 systemd[1]: modprobe@loop.service: Deactivated successfully. Dec 13 14:13:02.431343 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2024-12-13T14:13:02Z" level=debug msg="skipped missing lower profile" missing profile=oem Dec 13 14:13:06.809622 systemd[1]: Finished modprobe@loop.service. Dec 13 14:13:02.431410 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2024-12-13T14:13:02Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Dec 13 14:13:06.812000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:06.815000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-network-generator comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:06.812850 systemd[1]: Finished systemd-modules-load.service. Dec 13 14:13:02.431443 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2024-12-13T14:13:02Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Dec 13 14:13:06.815148 systemd[1]: Finished systemd-network-generator.service. Dec 13 14:13:06.817000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-remount-fs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:02.431919 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2024-12-13T14:13:02Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Dec 13 14:13:06.817455 systemd[1]: Finished systemd-remount-fs.service. Dec 13 14:13:02.432011 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2024-12-13T14:13:02Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Dec 13 14:13:06.820243 systemd[1]: Reached target network-pre.target. Dec 13 14:13:02.432047 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2024-12-13T14:13:02Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Dec 13 14:13:02.433337 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2024-12-13T14:13:02Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:20.10.torcx.tgz" reference=20.10 Dec 13 14:13:06.824582 systemd[1]: Mounting sys-fs-fuse-connections.mount... Dec 13 14:13:02.433420 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2024-12-13T14:13:02Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:com.coreos.cl.torcx.tgz" reference=com.coreos.cl Dec 13 14:13:06.829353 systemd[1]: Mounting sys-kernel-config.mount... Dec 13 14:13:02.433465 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2024-12-13T14:13:02Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3510.3.6: no such file or directory" path=/usr/share/oem/torcx/store/3510.3.6 Dec 13 14:13:02.433504 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2024-12-13T14:13:02Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Dec 13 14:13:02.433552 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2024-12-13T14:13:02Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3510.3.6: no such file or directory" path=/var/lib/torcx/store/3510.3.6 Dec 13 14:13:02.433590 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2024-12-13T14:13:02Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Dec 13 14:13:05.598550 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2024-12-13T14:13:05Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Dec 13 14:13:05.599145 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2024-12-13T14:13:05Z" level=debug msg="binaries propagated" assets="[/bin/containerd /bin/containerd-shim /bin/ctr /bin/docker /bin/docker-containerd /bin/docker-containerd-shim /bin/docker-init /bin/docker-proxy /bin/docker-runc /bin/dockerd /bin/runc /bin/tini]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Dec 13 14:13:05.599398 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2024-12-13T14:13:05Z" level=debug msg="networkd units propagated" assets="[/lib/systemd/network/50-docker.network /lib/systemd/network/90-docker-veth.network]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Dec 13 14:13:05.599899 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2024-12-13T14:13:05Z" level=debug msg="systemd units propagated" assets="[/lib/systemd/system/containerd.service /lib/systemd/system/docker.service /lib/systemd/system/docker.socket /lib/systemd/system/sockets.target.wants /lib/systemd/system/multi-user.target.wants]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Dec 13 14:13:05.600005 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2024-12-13T14:13:05Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Dec 13 14:13:05.600138 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2024-12-13T14:13:05Z" level=debug msg="system state sealed" content="[TORCX_LOWER_PROFILES=\"vendor\" TORCX_UPPER_PROFILE=\"\" TORCX_PROFILE_PATH=\"/run/torcx/profile.json\" TORCX_BINDIR=\"/run/torcx/bin\" TORCX_UNPACKDIR=\"/run/torcx/unpack\"]" path=/run/metadata/torcx Dec 13 14:13:06.835975 systemd[1]: remount-root.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Dec 13 14:13:06.840019 systemd[1]: Starting systemd-hwdb-update.service... Dec 13 14:13:06.844189 systemd[1]: Starting systemd-journal-flush.service... Dec 13 14:13:06.845946 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Dec 13 14:13:06.848987 systemd[1]: Starting systemd-random-seed.service... Dec 13 14:13:06.850756 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Dec 13 14:13:06.853809 systemd[1]: Starting systemd-sysctl.service... Dec 13 14:13:06.863548 systemd[1]: Mounted sys-fs-fuse-connections.mount. Dec 13 14:13:06.866202 systemd[1]: Mounted sys-kernel-config.mount. Dec 13 14:13:06.883913 systemd-journald[1509]: Time spent on flushing to /var/log/journal/ec2ab2fa919ba3cc00777d8eccc0dea1 is 75.199ms for 1116 entries. Dec 13 14:13:06.883913 systemd-journald[1509]: System Journal (/var/log/journal/ec2ab2fa919ba3cc00777d8eccc0dea1) is 8.0M, max 195.6M, 187.6M free. Dec 13 14:13:06.970432 systemd-journald[1509]: Received client request to flush runtime journal. Dec 13 14:13:06.897000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-random-seed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:06.919000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=flatcar-tmpfiles comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:06.922000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:06.972000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journal-flush comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:06.896559 systemd[1]: Finished systemd-random-seed.service. Dec 13 14:13:06.899287 systemd[1]: Reached target first-boot-complete.target. Dec 13 14:13:06.919444 systemd[1]: Finished flatcar-tmpfiles.service. Dec 13 14:13:06.921725 systemd[1]: Finished systemd-sysctl.service. Dec 13 14:13:06.926444 systemd[1]: Starting systemd-sysusers.service... Dec 13 14:13:06.972186 systemd[1]: Finished systemd-journal-flush.service. Dec 13 14:13:06.985277 systemd[1]: Finished systemd-sysusers.service. Dec 13 14:13:06.985000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-sysusers comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:06.989574 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... Dec 13 14:13:07.064143 systemd[1]: Finished systemd-udev-trigger.service. Dec 13 14:13:07.064000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:07.068681 systemd[1]: Starting systemd-udev-settle.service... Dec 13 14:13:07.088738 udevadm[1546]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Dec 13 14:13:07.089000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:07.089424 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. Dec 13 14:13:07.733951 systemd[1]: Finished systemd-hwdb-update.service. Dec 13 14:13:07.736000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-hwdb-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:07.737000 audit: BPF prog-id=21 op=LOAD Dec 13 14:13:07.737000 audit: BPF prog-id=22 op=LOAD Dec 13 14:13:07.737000 audit: BPF prog-id=7 op=UNLOAD Dec 13 14:13:07.737000 audit: BPF prog-id=8 op=UNLOAD Dec 13 14:13:07.740222 systemd[1]: Starting systemd-udevd.service... Dec 13 14:13:07.778271 systemd-udevd[1547]: Using default interface naming scheme 'v252'. Dec 13 14:13:07.823943 systemd[1]: Started systemd-udevd.service. Dec 13 14:13:07.823000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:07.825000 audit: BPF prog-id=23 op=LOAD Dec 13 14:13:07.828569 systemd[1]: Starting systemd-networkd.service... Dec 13 14:13:07.838000 audit: BPF prog-id=24 op=LOAD Dec 13 14:13:07.838000 audit: BPF prog-id=25 op=LOAD Dec 13 14:13:07.838000 audit: BPF prog-id=26 op=LOAD Dec 13 14:13:07.841326 systemd[1]: Starting systemd-userdbd.service... Dec 13 14:13:07.917468 systemd[1]: Condition check resulted in dev-ttyS0.device being skipped. Dec 13 14:13:07.930423 systemd[1]: Started systemd-userdbd.service. Dec 13 14:13:07.930000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-userdbd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:07.970191 (udev-worker)[1551]: Network interface NamePolicy= disabled on kernel command line. Dec 13 14:13:08.092751 systemd-networkd[1550]: lo: Link UP Dec 13 14:13:08.092773 systemd-networkd[1550]: lo: Gained carrier Dec 13 14:13:08.093703 systemd-networkd[1550]: Enumeration completed Dec 13 14:13:08.093863 systemd[1]: Started systemd-networkd.service. Dec 13 14:13:08.093000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:08.097871 systemd[1]: Starting systemd-networkd-wait-online.service... Dec 13 14:13:08.101606 systemd-networkd[1550]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Dec 13 14:13:08.107875 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Dec 13 14:13:08.108279 systemd-networkd[1550]: eth0: Link UP Dec 13 14:13:08.108616 systemd-networkd[1550]: eth0: Gained carrier Dec 13 14:13:08.121406 systemd-networkd[1550]: eth0: DHCPv4 address 172.31.24.120/20, gateway 172.31.16.1 acquired from 172.31.16.1 Dec 13 14:13:08.153699 kernel: BTRFS info: devid 1 device path /dev/disk/by-label/OEM changed to /dev/nvme0n1p6 scanned by (udev-worker) (1552) Dec 13 14:13:08.308447 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Dec 13 14:13:08.311136 systemd[1]: Finished systemd-udev-settle.service. Dec 13 14:13:08.311000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udev-settle comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:08.315296 systemd[1]: Starting lvm2-activation-early.service... Dec 13 14:13:08.343382 lvm[1662]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Dec 13 14:13:08.382239 systemd[1]: Finished lvm2-activation-early.service. Dec 13 14:13:08.382000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=lvm2-activation-early comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:08.384323 systemd[1]: Reached target cryptsetup.target. Dec 13 14:13:08.388110 systemd[1]: Starting lvm2-activation.service... Dec 13 14:13:08.396646 lvm[1663]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Dec 13 14:13:08.432357 systemd[1]: Finished lvm2-activation.service. Dec 13 14:13:08.434176 systemd[1]: Reached target local-fs-pre.target. Dec 13 14:13:08.432000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=lvm2-activation comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:08.435847 systemd[1]: var-lib-machines.mount was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Dec 13 14:13:08.436021 systemd[1]: Reached target local-fs.target. Dec 13 14:13:08.437683 systemd[1]: Reached target machines.target. Dec 13 14:13:08.441562 systemd[1]: Starting ldconfig.service... Dec 13 14:13:08.444403 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Dec 13 14:13:08.444777 systemd[1]: systemd-boot-system-token.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/LoaderFeatures-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Dec 13 14:13:08.447542 systemd[1]: Starting systemd-boot-update.service... Dec 13 14:13:08.452011 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service... Dec 13 14:13:08.457830 systemd[1]: Starting systemd-machine-id-commit.service... Dec 13 14:13:08.464741 systemd[1]: systemd-sysext.service was skipped because no trigger condition checks were met. Dec 13 14:13:08.464835 systemd[1]: ensure-sysext.service was skipped because no trigger condition checks were met. Dec 13 14:13:08.467166 systemd[1]: Starting systemd-tmpfiles-setup.service... Dec 13 14:13:08.480488 systemd[1]: boot.automount: Got automount request for /boot, triggered by 1665 (bootctl) Dec 13 14:13:08.482982 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service... Dec 13 14:13:08.503990 systemd-tmpfiles[1668]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Dec 13 14:13:08.506690 systemd-tmpfiles[1668]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Dec 13 14:13:08.510727 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service. Dec 13 14:13:08.511000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck@dev-disk-by\x2dlabel-OEM comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:08.514130 systemd-tmpfiles[1668]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Dec 13 14:13:08.561869 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Dec 13 14:13:08.564359 systemd[1]: Finished systemd-machine-id-commit.service. Dec 13 14:13:08.562000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-machine-id-commit comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:08.617223 systemd-fsck[1673]: fsck.fat 4.2 (2021-01-31) Dec 13 14:13:08.617223 systemd-fsck[1673]: /dev/nvme0n1p1: 236 files, 117175/258078 clusters Dec 13 14:13:08.622348 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service. Dec 13 14:13:08.623000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:08.627071 systemd[1]: Mounting boot.mount... Dec 13 14:13:08.647510 systemd[1]: Mounted boot.mount. Dec 13 14:13:08.687954 systemd[1]: Finished systemd-boot-update.service. Dec 13 14:13:08.688000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-boot-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:08.890852 systemd[1]: Finished systemd-tmpfiles-setup.service. Dec 13 14:13:08.891000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:08.895304 systemd[1]: Starting audit-rules.service... Dec 13 14:13:08.899218 systemd[1]: Starting clean-ca-certificates.service... Dec 13 14:13:08.904309 systemd[1]: Starting systemd-journal-catalog-update.service... Dec 13 14:13:08.908000 audit: BPF prog-id=27 op=LOAD Dec 13 14:13:08.913000 audit: BPF prog-id=28 op=LOAD Dec 13 14:13:08.911794 systemd[1]: Starting systemd-resolved.service... Dec 13 14:13:08.918009 systemd[1]: Starting systemd-timesyncd.service... Dec 13 14:13:08.922457 systemd[1]: Starting systemd-update-utmp.service... Dec 13 14:13:08.925000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=clean-ca-certificates comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:08.925647 systemd[1]: Finished clean-ca-certificates.service. Dec 13 14:13:08.927782 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Dec 13 14:13:08.942000 audit[1693]: SYSTEM_BOOT pid=1693 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg=' comm="systemd-update-utmp" exe="/usr/lib/systemd/systemd-update-utmp" hostname=? addr=? terminal=? res=success' Dec 13 14:13:08.950613 systemd[1]: Finished systemd-update-utmp.service. Dec 13 14:13:08.950000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-update-utmp comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:09.010563 augenrules[1707]: No rules Dec 13 14:13:09.008000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Dec 13 14:13:09.008000 audit[1707]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=fffff2713240 a2=420 a3=0 items=0 ppid=1687 pid=1707 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditctl" exe="/usr/sbin/auditctl" subj=system_u:system_r:kernel_t:s0 key=(null) Dec 13 14:13:09.008000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573 Dec 13 14:13:09.013561 systemd[1]: Finished audit-rules.service. Dec 13 14:13:09.022232 systemd[1]: Finished systemd-journal-catalog-update.service. Dec 13 14:13:09.097521 systemd[1]: Started systemd-timesyncd.service. Dec 13 14:13:09.099445 systemd[1]: Reached target time-set.target. Dec 13 14:13:09.122748 ldconfig[1664]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Dec 13 14:13:09.127783 systemd-resolved[1691]: Positive Trust Anchors: Dec 13 14:13:09.127805 systemd-resolved[1691]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Dec 13 14:13:09.127855 systemd-resolved[1691]: 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 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Dec 13 14:13:09.131421 systemd[1]: Finished ldconfig.service. Dec 13 14:13:09.135697 systemd[1]: Starting systemd-update-done.service... Dec 13 14:13:09.152331 systemd[1]: Finished systemd-update-done.service. Dec 13 14:13:09.162713 systemd-resolved[1691]: Defaulting to hostname 'linux'. Dec 13 14:13:09.166070 systemd[1]: Started systemd-resolved.service. Dec 13 14:13:09.167900 systemd[1]: Reached target network.target. Dec 13 14:13:09.169425 systemd[1]: Reached target nss-lookup.target. Dec 13 14:13:09.171027 systemd[1]: Reached target sysinit.target. Dec 13 14:13:09.172769 systemd[1]: Started motdgen.path. Dec 13 14:13:09.174167 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path. Dec 13 14:13:09.176583 systemd[1]: Started logrotate.timer. Dec 13 14:13:09.178115 systemd[1]: Started mdadm.timer. Dec 13 14:13:09.179450 systemd[1]: Started systemd-tmpfiles-clean.timer. Dec 13 14:13:09.181101 systemd[1]: update-engine-stub.timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Dec 13 14:13:09.181165 systemd[1]: Reached target paths.target. Dec 13 14:13:09.182540 systemd[1]: Reached target timers.target. Dec 13 14:13:09.184694 systemd[1]: Listening on dbus.socket. Dec 13 14:13:09.188129 systemd[1]: Starting docker.socket... Dec 13 14:13:09.194475 systemd[1]: Listening on sshd.socket. Dec 13 14:13:09.196181 systemd[1]: systemd-pcrphase-sysinit.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Dec 13 14:13:09.197128 systemd[1]: Listening on docker.socket. Dec 13 14:13:09.198787 systemd[1]: Reached target sockets.target. Dec 13 14:13:09.200281 systemd[1]: Reached target basic.target. Dec 13 14:13:09.201808 systemd[1]: addon-config@usr-share-oem.service was skipped because no trigger condition checks were met. Dec 13 14:13:09.201869 systemd[1]: addon-run@usr-share-oem.service was skipped because no trigger condition checks were met. Dec 13 14:13:09.203875 systemd[1]: Starting containerd.service... Dec 13 14:13:09.208290 systemd[1]: Starting coreos-metadata-sshkeys@core.service... Dec 13 14:13:09.212594 systemd[1]: Starting coreos-metadata.service... Dec 13 14:13:09.216558 systemd[1]: Starting dbus.service... Dec 13 14:13:09.221353 systemd[1]: Starting enable-oem-cloudinit.service... Dec 13 14:13:09.229008 systemd[1]: Starting extend-filesystems.service... Dec 13 14:13:09.230921 systemd[1]: flatcar-setup-environment.service was skipped because of an unmet condition check (ConditionPathExists=/usr/share/oem/bin/flatcar-setup-environment). Dec 13 14:13:09.290722 jq[1720]: false Dec 13 14:13:09.234978 systemd[1]: Starting motdgen.service... Dec 13 14:13:09.238603 systemd[1]: Starting ssh-key-proc-cmdline.service... Dec 13 14:13:09.243022 systemd[1]: Starting sshd-keygen.service... Dec 13 14:13:09.250276 systemd[1]: Starting systemd-logind.service... Dec 13 14:13:09.252022 systemd[1]: systemd-pcrphase.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Dec 13 14:13:09.313004 jq[1728]: true Dec 13 14:13:09.252142 systemd[1]: tcsd.service was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Dec 13 14:13:09.253092 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Dec 13 14:13:09.256240 systemd[1]: Starting update-engine.service... Dec 13 14:13:09.261870 systemd[1]: Starting update-ssh-keys-after-ignition.service... Dec 13 14:13:09.269208 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Dec 13 14:13:09.269693 systemd[1]: Condition check resulted in enable-oem-cloudinit.service being skipped. Dec 13 14:13:09.317647 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Dec 13 14:13:09.320001 systemd[1]: Finished ssh-key-proc-cmdline.service. Dec 13 14:13:09.350981 jq[1744]: true Dec 13 14:13:09.364361 systemd[1]: motdgen.service: Deactivated successfully. Dec 13 14:13:09.366954 extend-filesystems[1721]: Found nvme0n1 Dec 13 14:13:09.366954 extend-filesystems[1721]: Found nvme0n1p1 Dec 13 14:13:09.366954 extend-filesystems[1721]: Found nvme0n1p2 Dec 13 14:13:09.366954 extend-filesystems[1721]: Found nvme0n1p3 Dec 13 14:13:09.366954 extend-filesystems[1721]: Found usr Dec 13 14:13:09.366954 extend-filesystems[1721]: Found nvme0n1p4 Dec 13 14:13:09.366954 extend-filesystems[1721]: Found nvme0n1p6 Dec 13 14:13:09.366954 extend-filesystems[1721]: Found nvme0n1p7 Dec 13 14:13:09.366954 extend-filesystems[1721]: Found nvme0n1p9 Dec 13 14:13:09.366954 extend-filesystems[1721]: Checking size of /dev/nvme0n1p9 Dec 13 14:13:09.364751 systemd[1]: Finished motdgen.service. Dec 13 14:13:09.388839 systemd-networkd[1550]: eth0: Gained IPv6LL Dec 13 14:13:09.399902 systemd-timesyncd[1692]: Contacted time server 23.111.186.186:123 (0.flatcar.pool.ntp.org). Dec 13 14:13:09.400328 systemd-timesyncd[1692]: Initial clock synchronization to Fri 2024-12-13 14:13:09.357453 UTC. Dec 13 14:13:09.408365 systemd[1]: Finished systemd-networkd-wait-online.service. Dec 13 14:13:09.410431 systemd[1]: Reached target network-online.target. Dec 13 14:13:09.414262 systemd[1]: Started amazon-ssm-agent.service. Dec 13 14:13:09.419615 systemd[1]: Started nvidia.service. Dec 13 14:13:09.480236 update_engine[1726]: I1213 14:13:09.479138 1726 main.cc:92] Flatcar Update Engine starting Dec 13 14:13:09.481690 dbus-daemon[1719]: [system] SELinux support is enabled Dec 13 14:13:09.483392 extend-filesystems[1721]: Resized partition /dev/nvme0n1p9 Dec 13 14:13:09.485985 systemd[1]: Started dbus.service. Dec 13 14:13:09.509522 extend-filesystems[1774]: resize2fs 1.46.5 (30-Dec-2021) Dec 13 14:13:09.491439 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Dec 13 14:13:09.491483 systemd[1]: Reached target system-config.target. Dec 13 14:13:09.502911 systemd[1]: user-cloudinit-proc-cmdline.service was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Dec 13 14:13:09.502951 systemd[1]: Reached target user-config.target. Dec 13 14:13:09.528249 kernel: EXT4-fs (nvme0n1p9): resizing filesystem from 553472 to 1489915 blocks Dec 13 14:13:09.562016 dbus-daemon[1719]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.0' (uid=244 pid=1550 comm="/usr/lib/systemd/systemd-networkd" label="system_u:system_r:kernel_t:s0") Dec 13 14:13:09.571084 update_engine[1726]: I1213 14:13:09.569938 1726 update_check_scheduler.cc:74] Next update check in 2m52s Dec 13 14:13:09.631190 systemd[1]: Starting systemd-hostnamed.service... Dec 13 14:13:09.633099 systemd[1]: Started update-engine.service. Dec 13 14:13:09.638479 systemd[1]: Started locksmithd.service. Dec 13 14:13:09.664702 kernel: EXT4-fs (nvme0n1p9): resized filesystem to 1489915 Dec 13 14:13:09.678973 bash[1778]: Updated "/home/core/.ssh/authorized_keys" Dec 13 14:13:09.680361 extend-filesystems[1774]: Filesystem at /dev/nvme0n1p9 is mounted on /; on-line resizing required Dec 13 14:13:09.680361 extend-filesystems[1774]: old_desc_blocks = 1, new_desc_blocks = 1 Dec 13 14:13:09.680361 extend-filesystems[1774]: The filesystem on /dev/nvme0n1p9 is now 1489915 (4k) blocks long. Dec 13 14:13:09.691926 extend-filesystems[1721]: Resized filesystem in /dev/nvme0n1p9 Dec 13 14:13:09.683087 systemd[1]: Finished update-ssh-keys-after-ignition.service. Dec 13 14:13:09.687292 systemd[1]: extend-filesystems.service: Deactivated successfully. Dec 13 14:13:09.687619 systemd[1]: Finished extend-filesystems.service. Dec 13 14:13:09.752444 env[1734]: time="2024-12-13T14:13:09.752346346Z" level=info msg="starting containerd" revision=92b3a9d6f1b3bcc6dc74875cfdea653fe39f09c2 version=1.6.16 Dec 13 14:13:09.767336 amazon-ssm-agent[1757]: 2024/12/13 14:13:09 Failed to load instance info from vault. RegistrationKey does not exist. Dec 13 14:13:09.777831 systemd-logind[1725]: Watching system buttons on /dev/input/event0 (Power Button) Dec 13 14:13:09.777886 systemd-logind[1725]: Watching system buttons on /dev/input/event1 (Sleep Button) Dec 13 14:13:09.780050 systemd-logind[1725]: New seat seat0. Dec 13 14:13:09.783056 amazon-ssm-agent[1757]: Initializing new seelog logger Dec 13 14:13:09.783274 amazon-ssm-agent[1757]: New Seelog Logger Creation Complete Dec 13 14:13:09.783390 amazon-ssm-agent[1757]: 2024/12/13 14:13:09 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Dec 13 14:13:09.783390 amazon-ssm-agent[1757]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Dec 13 14:13:09.785932 systemd[1]: Started systemd-logind.service. Dec 13 14:13:09.788533 amazon-ssm-agent[1757]: 2024/12/13 14:13:09 processing appconfig overrides Dec 13 14:13:09.908430 env[1734]: time="2024-12-13T14:13:09.908208851Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Dec 13 14:13:09.908691 env[1734]: time="2024-12-13T14:13:09.908611739Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Dec 13 14:13:09.918034 env[1734]: time="2024-12-13T14:13:09.917948795Z" 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/5.15.173-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Dec 13 14:13:09.918034 env[1734]: time="2024-12-13T14:13:09.918022715Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Dec 13 14:13:09.918592 env[1734]: time="2024-12-13T14:13:09.918526715Z" 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 Dec 13 14:13:09.918592 env[1734]: time="2024-12-13T14:13:09.918585443Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Dec 13 14:13:09.918773 env[1734]: time="2024-12-13T14:13:09.918620459Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" Dec 13 14:13:09.918773 env[1734]: time="2024-12-13T14:13:09.918645635Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Dec 13 14:13:09.918901 env[1734]: time="2024-12-13T14:13:09.918880439Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Dec 13 14:13:09.919413 env[1734]: time="2024-12-13T14:13:09.919351475Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Dec 13 14:13:09.923772 env[1734]: time="2024-12-13T14:13:09.919643555Z" 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 Dec 13 14:13:09.923913 env[1734]: time="2024-12-13T14:13:09.923772263Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Dec 13 14:13:09.924000 env[1734]: time="2024-12-13T14:13:09.923956511Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" Dec 13 14:13:09.924092 env[1734]: time="2024-12-13T14:13:09.923995763Z" level=info msg="metadata content store policy set" policy=shared Dec 13 14:13:09.937022 env[1734]: time="2024-12-13T14:13:09.936910079Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Dec 13 14:13:09.937204 env[1734]: time="2024-12-13T14:13:09.937053227Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Dec 13 14:13:09.937204 env[1734]: time="2024-12-13T14:13:09.937090451Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Dec 13 14:13:09.937357 env[1734]: time="2024-12-13T14:13:09.937220171Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Dec 13 14:13:09.937424 env[1734]: time="2024-12-13T14:13:09.937258751Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Dec 13 14:13:09.937424 env[1734]: time="2024-12-13T14:13:09.937408679Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Dec 13 14:13:09.937527 env[1734]: time="2024-12-13T14:13:09.937441367Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Dec 13 14:13:09.938238 env[1734]: time="2024-12-13T14:13:09.938172575Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Dec 13 14:13:09.938358 env[1734]: time="2024-12-13T14:13:09.938243411Z" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1 Dec 13 14:13:09.938358 env[1734]: time="2024-12-13T14:13:09.938277671Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Dec 13 14:13:09.938358 env[1734]: time="2024-12-13T14:13:09.938308127Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Dec 13 14:13:09.938358 env[1734]: time="2024-12-13T14:13:09.938337719Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Dec 13 14:13:09.938734 env[1734]: time="2024-12-13T14:13:09.938685479Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Dec 13 14:13:09.938968 env[1734]: time="2024-12-13T14:13:09.938919035Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Dec 13 14:13:09.939691 env[1734]: time="2024-12-13T14:13:09.939604907Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Dec 13 14:13:09.939792 env[1734]: time="2024-12-13T14:13:09.939719819Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Dec 13 14:13:09.939792 env[1734]: time="2024-12-13T14:13:09.939758147Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Dec 13 14:13:09.939929 env[1734]: time="2024-12-13T14:13:09.939872987Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Dec 13 14:13:09.940032 env[1734]: time="2024-12-13T14:13:09.939905891Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Dec 13 14:13:09.940097 env[1734]: time="2024-12-13T14:13:09.940036571Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Dec 13 14:13:09.940097 env[1734]: time="2024-12-13T14:13:09.940068695Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Dec 13 14:13:09.940197 env[1734]: time="2024-12-13T14:13:09.940101647Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Dec 13 14:13:09.940197 env[1734]: time="2024-12-13T14:13:09.940131983Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Dec 13 14:13:09.940197 env[1734]: time="2024-12-13T14:13:09.940161047Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Dec 13 14:13:09.940197 env[1734]: time="2024-12-13T14:13:09.940189367Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Dec 13 14:13:09.940403 env[1734]: time="2024-12-13T14:13:09.940223147Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Dec 13 14:13:09.940572 env[1734]: time="2024-12-13T14:13:09.940511843Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Dec 13 14:13:09.940646 env[1734]: time="2024-12-13T14:13:09.940607987Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Dec 13 14:13:09.940746 env[1734]: time="2024-12-13T14:13:09.940644887Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Dec 13 14:13:09.940746 env[1734]: time="2024-12-13T14:13:09.940697747Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Dec 13 14:13:09.940848 env[1734]: time="2024-12-13T14:13:09.940737059Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1 Dec 13 14:13:09.940848 env[1734]: time="2024-12-13T14:13:09.940766627Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Dec 13 14:13:09.940848 env[1734]: time="2024-12-13T14:13:09.940806959Z" level=error msg="failed to initialize a tracing processor \"otlp\"" error="no OpenTelemetry endpoint: skip plugin" Dec 13 14:13:09.941015 env[1734]: time="2024-12-13T14:13:09.940879235Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Dec 13 14:13:09.941372 env[1734]: time="2024-12-13T14:13:09.941255243Z" 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 BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 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.6 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} 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}" Dec 13 14:13:09.943113 env[1734]: time="2024-12-13T14:13:09.941375903Z" level=info msg="Connect containerd service" Dec 13 14:13:09.943113 env[1734]: time="2024-12-13T14:13:09.941436683Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Dec 13 14:13:09.943113 env[1734]: time="2024-12-13T14:13:09.942583595Z" 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" Dec 13 14:13:09.943378 env[1734]: time="2024-12-13T14:13:09.943307615Z" level=info msg="Start subscribing containerd event" Dec 13 14:13:09.943437 env[1734]: time="2024-12-13T14:13:09.943392155Z" level=info msg="Start recovering state" Dec 13 14:13:09.943548 env[1734]: time="2024-12-13T14:13:09.943502123Z" level=info msg="Start event monitor" Dec 13 14:13:09.943624 env[1734]: time="2024-12-13T14:13:09.943557707Z" level=info msg="Start snapshots syncer" Dec 13 14:13:09.943624 env[1734]: time="2024-12-13T14:13:09.943585367Z" level=info msg="Start cni network conf syncer for default" Dec 13 14:13:09.943624 env[1734]: time="2024-12-13T14:13:09.943604975Z" level=info msg="Start streaming server" Dec 13 14:13:09.963263 env[1734]: time="2024-12-13T14:13:09.963151775Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Dec 13 14:13:09.963480 env[1734]: time="2024-12-13T14:13:09.963365855Z" level=info msg=serving... address=/run/containerd/containerd.sock Dec 13 14:13:09.963480 env[1734]: time="2024-12-13T14:13:09.963520775Z" level=info msg="containerd successfully booted in 0.214090s" Dec 13 14:13:09.963649 systemd[1]: Started containerd.service. Dec 13 14:13:09.977612 systemd[1]: nvidia.service: Deactivated successfully. Dec 13 14:13:10.262384 coreos-metadata[1717]: Dec 13 14:13:10.261 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Dec 13 14:13:10.264035 coreos-metadata[1717]: Dec 13 14:13:10.263 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/public-keys: Attempt #1 Dec 13 14:13:10.266522 coreos-metadata[1718]: Dec 13 14:13:10.263 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Dec 13 14:13:10.267443 coreos-metadata[1717]: Dec 13 14:13:10.267 INFO Fetch successful Dec 13 14:13:10.267766 coreos-metadata[1717]: Dec 13 14:13:10.267 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/public-keys/0/openssh-key: Attempt #1 Dec 13 14:13:10.270764 coreos-metadata[1718]: Dec 13 14:13:10.269 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/instance-id: Attempt #1 Dec 13 14:13:10.270908 coreos-metadata[1717]: Dec 13 14:13:10.270 INFO Fetch successful Dec 13 14:13:10.273489 coreos-metadata[1718]: Dec 13 14:13:10.273 INFO Fetch successful Dec 13 14:13:10.273489 coreos-metadata[1718]: Dec 13 14:13:10.273 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/instance-type: Attempt #1 Dec 13 14:13:10.273887 unknown[1717]: wrote ssh authorized keys file for user: core Dec 13 14:13:10.281813 coreos-metadata[1718]: Dec 13 14:13:10.281 INFO Fetch successful Dec 13 14:13:10.281813 coreos-metadata[1718]: Dec 13 14:13:10.281 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/local-ipv4: Attempt #1 Dec 13 14:13:10.282971 coreos-metadata[1718]: Dec 13 14:13:10.282 INFO Fetch successful Dec 13 14:13:10.282971 coreos-metadata[1718]: Dec 13 14:13:10.282 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/public-ipv4: Attempt #1 Dec 13 14:13:10.284309 coreos-metadata[1718]: Dec 13 14:13:10.284 INFO Fetch successful Dec 13 14:13:10.284309 coreos-metadata[1718]: Dec 13 14:13:10.284 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/placement/availability-zone: Attempt #1 Dec 13 14:13:10.287038 coreos-metadata[1718]: Dec 13 14:13:10.286 INFO Fetch successful Dec 13 14:13:10.287038 coreos-metadata[1718]: Dec 13 14:13:10.286 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/hostname: Attempt #1 Dec 13 14:13:10.288449 coreos-metadata[1718]: Dec 13 14:13:10.288 INFO Fetch successful Dec 13 14:13:10.288449 coreos-metadata[1718]: Dec 13 14:13:10.288 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/public-hostname: Attempt #1 Dec 13 14:13:10.289601 coreos-metadata[1718]: Dec 13 14:13:10.289 INFO Fetch successful Dec 13 14:13:10.289601 coreos-metadata[1718]: Dec 13 14:13:10.289 INFO Fetching http://169.254.169.254/2019-10-01/dynamic/instance-identity/document: Attempt #1 Dec 13 14:13:10.293887 coreos-metadata[1718]: Dec 13 14:13:10.293 INFO Fetch successful Dec 13 14:13:10.309561 dbus-daemon[1719]: [system] Successfully activated service 'org.freedesktop.hostname1' Dec 13 14:13:10.312014 systemd[1]: Started systemd-hostnamed.service. Dec 13 14:13:10.327752 update-ssh-keys[1875]: Updated "/home/core/.ssh/authorized_keys" Dec 13 14:13:10.329323 systemd[1]: Finished coreos-metadata-sshkeys@core.service. Dec 13 14:13:10.335900 dbus-daemon[1719]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.6' (uid=0 pid=1780 comm="/usr/lib/systemd/systemd-hostnamed" label="system_u:system_r:kernel_t:s0") Dec 13 14:13:10.339086 systemd[1]: Finished coreos-metadata.service. Dec 13 14:13:10.344871 systemd[1]: Starting etcd-member.service... Dec 13 14:13:10.349586 systemd[1]: Starting polkit.service... Dec 13 14:13:10.402430 polkitd[1880]: Started polkitd version 121 Dec 13 14:13:10.430132 polkitd[1880]: Loading rules from directory /etc/polkit-1/rules.d Dec 13 14:13:10.430592 polkitd[1880]: Loading rules from directory /usr/share/polkit-1/rules.d Dec 13 14:13:10.437604 polkitd[1880]: Finished loading, compiling and executing 2 rules Dec 13 14:13:10.438842 dbus-daemon[1719]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Dec 13 14:13:10.439149 systemd[1]: Started polkit.service. Dec 13 14:13:10.442017 polkitd[1880]: Acquired the name org.freedesktop.PolicyKit1 on the system bus Dec 13 14:13:10.466265 systemd-resolved[1691]: System hostname changed to 'ip-172-31-24-120'. Dec 13 14:13:10.466273 systemd-hostnamed[1780]: Hostname set to (transient) Dec 13 14:13:10.533080 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO Create new startup processor Dec 13 14:13:10.536036 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [LongRunningPluginsManager] registered plugins: {} Dec 13 14:13:10.536218 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO Initializing bookkeeping folders Dec 13 14:13:10.536332 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO removing the completed state files Dec 13 14:13:10.536444 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO Initializing bookkeeping folders for long running plugins Dec 13 14:13:10.536583 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO Initializing replies folder for MDS reply requests that couldn't reach the service Dec 13 14:13:10.536717 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO Initializing healthcheck folders for long running plugins Dec 13 14:13:10.536830 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO Initializing locations for inventory plugin Dec 13 14:13:10.536956 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO Initializing default location for custom inventory Dec 13 14:13:10.537077 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO Initializing default location for file inventory Dec 13 14:13:10.537187 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO Initializing default location for role inventory Dec 13 14:13:10.537298 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO Init the cloudwatchlogs publisher Dec 13 14:13:10.537411 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [instanceID=i-0357efefeb7dad178] Successfully loaded platform independent plugin aws:runPowerShellScript Dec 13 14:13:10.537523 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [instanceID=i-0357efefeb7dad178] Successfully loaded platform independent plugin aws:updateSsmAgent Dec 13 14:13:10.537636 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [instanceID=i-0357efefeb7dad178] Successfully loaded platform independent plugin aws:configureDocker Dec 13 14:13:10.538034 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [instanceID=i-0357efefeb7dad178] Successfully loaded platform independent plugin aws:runDockerAction Dec 13 14:13:10.538165 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [instanceID=i-0357efefeb7dad178] Successfully loaded platform independent plugin aws:refreshAssociation Dec 13 14:13:10.538286 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [instanceID=i-0357efefeb7dad178] Successfully loaded platform independent plugin aws:downloadContent Dec 13 14:13:10.538407 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [instanceID=i-0357efefeb7dad178] Successfully loaded platform independent plugin aws:softwareInventory Dec 13 14:13:10.538527 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [instanceID=i-0357efefeb7dad178] Successfully loaded platform independent plugin aws:configurePackage Dec 13 14:13:10.542431 systemd[1]: Starting docker.service... Dec 13 14:13:10.552638 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [instanceID=i-0357efefeb7dad178] Successfully loaded platform independent plugin aws:runDocument Dec 13 14:13:10.554805 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [instanceID=i-0357efefeb7dad178] Successfully loaded platform dependent plugin aws:runShellScript Dec 13 14:13:10.555051 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO Starting Agent: amazon-ssm-agent - v2.3.1319.0 Dec 13 14:13:10.555193 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO OS: linux, Arch: arm64 Dec 13 14:13:10.555750 amazon-ssm-agent[1757]: datastore file /var/lib/amazon/ssm/i-0357efefeb7dad178/longrunningplugins/datastore/store doesn't exist - no long running plugins to execute Dec 13 14:13:10.635157 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [MessageGatewayService] Starting session document processing engine... Dec 13 14:13:10.700065 env[1908]: time="2024-12-13T14:13:10.699718211Z" level=info msg="Starting up" Dec 13 14:13:10.703981 env[1908]: time="2024-12-13T14:13:10.703625831Z" level=info msg="parsed scheme: \"unix\"" module=grpc Dec 13 14:13:10.704849 env[1908]: time="2024-12-13T14:13:10.704795066Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc Dec 13 14:13:10.705120 env[1908]: time="2024-12-13T14:13:10.705086291Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/libcontainerd/docker-containerd.sock 0 }] }" module=grpc Dec 13 14:13:10.705231 env[1908]: time="2024-12-13T14:13:10.705203406Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc Dec 13 14:13:10.709280 env[1908]: time="2024-12-13T14:13:10.708922074Z" level=info msg="parsed scheme: \"unix\"" module=grpc Dec 13 14:13:10.709280 env[1908]: time="2024-12-13T14:13:10.708960206Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc Dec 13 14:13:10.709280 env[1908]: time="2024-12-13T14:13:10.708993863Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/libcontainerd/docker-containerd.sock 0 }] }" module=grpc Dec 13 14:13:10.709280 env[1908]: time="2024-12-13T14:13:10.709021020Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc Dec 13 14:13:10.719487 systemd[1]: var-lib-docker-check\x2doverlayfs\x2dsupport3297413886-merged.mount: Deactivated successfully. Dec 13 14:13:10.730142 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [OfflineService] Starting document processing engine... Dec 13 14:13:10.796088 env[1908]: time="2024-12-13T14:13:10.795958076Z" level=info msg="Loading containers: start." Dec 13 14:13:10.824469 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [OfflineService] [EngineProcessor] Starting Dec 13 14:13:10.919269 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [OfflineService] [EngineProcessor] Initial processing Dec 13 14:13:10.928300 locksmithd[1781]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Dec 13 14:13:11.014003 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [MessagingDeliveryService] Starting document processing engine... Dec 13 14:13:11.026692 kernel: Initializing XFRM netlink socket Dec 13 14:13:11.071012 env[1908]: time="2024-12-13T14:13:11.070897751Z" level=info msg="Default bridge (docker0) is assigned with an IP address 172.17.0.0/16. Daemon option --bip can be used to set a preferred IP address" Dec 13 14:13:11.073053 (udev-worker)[1548]: Network interface NamePolicy= disabled on kernel command line. Dec 13 14:13:11.108911 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [MessagingDeliveryService] [EngineProcessor] Starting Dec 13 14:13:11.184034 systemd-networkd[1550]: docker0: Link UP Dec 13 14:13:11.204024 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [MessagingDeliveryService] [EngineProcessor] Initial processing Dec 13 14:13:11.209070 env[1908]: time="2024-12-13T14:13:11.209001863Z" level=info msg="Loading containers: done." Dec 13 14:13:11.229068 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck2421168976-merged.mount: Deactivated successfully. Dec 13 14:13:11.248177 env[1908]: time="2024-12-13T14:13:11.248110335Z" 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 Dec 13 14:13:11.248849 env[1908]: time="2024-12-13T14:13:11.248819160Z" level=info msg="Docker daemon" commit=112bdf3343 graphdriver(s)=overlay2 version=20.10.23 Dec 13 14:13:11.249227 env[1908]: time="2024-12-13T14:13:11.249189979Z" level=info msg="Daemon has completed initialization" Dec 13 14:13:11.281157 systemd[1]: Started docker.service. Dec 13 14:13:11.291567 env[1908]: time="2024-12-13T14:13:11.291463680Z" level=info msg="API listen on /run/docker.sock" Dec 13 14:13:11.292983 etcd-wrapper[1884]: Error response from daemon: No such container: etcd-member Dec 13 14:13:11.299343 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [OfflineService] Starting message polling Dec 13 14:13:11.336056 etcd-wrapper[2031]: Error: No such container: etcd-member Dec 13 14:13:11.394858 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [OfflineService] Starting send replies to MDS Dec 13 14:13:11.412624 etcd-wrapper[2047]: Unable to find image 'quay.io/coreos/etcd:v3.5.16' locally Dec 13 14:13:11.490566 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [LongRunningPluginsManager] starting long running plugin manager Dec 13 14:13:11.586489 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [LongRunningPluginsManager] there aren't any long running plugin to execute Dec 13 14:13:11.682581 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [MessagingDeliveryService] Starting message polling Dec 13 14:13:11.778814 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [MessagingDeliveryService] Starting send replies to MDS Dec 13 14:13:11.798636 sshd_keygen[1746]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Dec 13 14:13:11.836024 systemd[1]: Finished sshd-keygen.service. Dec 13 14:13:11.840583 systemd[1]: Starting issuegen.service... Dec 13 14:13:11.852539 systemd[1]: issuegen.service: Deactivated successfully. Dec 13 14:13:11.852944 systemd[1]: Finished issuegen.service. Dec 13 14:13:11.857749 systemd[1]: Starting systemd-user-sessions.service... Dec 13 14:13:11.873348 systemd[1]: Finished systemd-user-sessions.service. Dec 13 14:13:11.876295 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [instanceID=i-0357efefeb7dad178] Starting association polling Dec 13 14:13:11.878770 systemd[1]: Started getty@tty1.service. Dec 13 14:13:11.883356 systemd[1]: Started serial-getty@ttyS0.service. Dec 13 14:13:11.885454 systemd[1]: Reached target getty.target. Dec 13 14:13:11.971974 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [MessagingDeliveryService] [Association] [EngineProcessor] Starting Dec 13 14:13:12.068844 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [MessagingDeliveryService] [Association] Launching response handler Dec 13 14:13:12.165910 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [MessagingDeliveryService] [Association] [EngineProcessor] Initial processing Dec 13 14:13:12.263176 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [MessagingDeliveryService] [Association] Initializing association scheduling service Dec 13 14:13:12.360628 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [MessagingDeliveryService] [Association] Association scheduling service initialized Dec 13 14:13:12.458311 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [MessageGatewayService] [EngineProcessor] Starting Dec 13 14:13:12.556150 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [MessageGatewayService] SSM Agent is trying to setup control channel for Session Manager module. Dec 13 14:13:12.654193 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [MessageGatewayService] Setting up websocket for controlchannel for instance: i-0357efefeb7dad178, requestId: 85dd9a80-87a0-47ad-b033-fa4d3f3f324b Dec 13 14:13:12.752492 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [HealthCheck] HealthCheck reporting agent health. Dec 13 14:13:12.850911 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [LongRunningPluginsManager] There are no long running plugins currently getting executed - skipping their healthcheck Dec 13 14:13:12.949568 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [MessageGatewayService] listening reply. Dec 13 14:13:13.048458 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [StartupProcessor] Executing startup processor tasks Dec 13 14:13:13.147439 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [StartupProcessor] Write to serial port: Amazon SSM Agent v2.3.1319.0 is running Dec 13 14:13:13.246632 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [StartupProcessor] Write to serial port: OsProductName: Flatcar Container Linux by Kinvolk Dec 13 14:13:13.346099 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [StartupProcessor] Write to serial port: OsVersion: 3510.3.6 Dec 13 14:13:13.445640 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [MessageGatewayService] Opening websocket connection to: wss://ssmmessages.us-west-2.amazonaws.com/v1/control-channel/i-0357efefeb7dad178?role=subscribe&stream=input Dec 13 14:13:13.545786 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [MessageGatewayService] Successfully opened websocket connection to: wss://ssmmessages.us-west-2.amazonaws.com/v1/control-channel/i-0357efefeb7dad178?role=subscribe&stream=input Dec 13 14:13:13.645945 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [MessageGatewayService] Starting receiving message from control channel Dec 13 14:13:13.746108 amazon-ssm-agent[1757]: 2024-12-13 14:13:10 INFO [MessageGatewayService] [EngineProcessor] Initial processing Dec 13 14:13:13.930697 etcd-wrapper[2047]: v3.5.16: Pulling from coreos/etcd Dec 13 14:13:14.485638 etcd-wrapper[2047]: d10f96ed9607: Pulling fs layer Dec 13 14:13:14.485638 etcd-wrapper[2047]: 2ae710cd8bfe: Pulling fs layer Dec 13 14:13:14.485638 etcd-wrapper[2047]: d462aa345367: Pulling fs layer Dec 13 14:13:14.485638 etcd-wrapper[2047]: 0f8b424aa0b9: Pulling fs layer Dec 13 14:13:14.485638 etcd-wrapper[2047]: d557676654e5: Pulling fs layer Dec 13 14:13:14.485638 etcd-wrapper[2047]: c8022d07192e: Pulling fs layer Dec 13 14:13:14.485638 etcd-wrapper[2047]: d858cbc252ad: Pulling fs layer Dec 13 14:13:14.485638 etcd-wrapper[2047]: 1069fc2daed1: Pulling fs layer Dec 13 14:13:14.485638 etcd-wrapper[2047]: b40161cd83fc: Pulling fs layer Dec 13 14:13:14.485638 etcd-wrapper[2047]: 5318d93a3a65: Pulling fs layer Dec 13 14:13:14.485638 etcd-wrapper[2047]: 307c1adadb60: Pulling fs layer Dec 13 14:13:14.485638 etcd-wrapper[2047]: f9b3c65df792: Pulling fs layer Dec 13 14:13:14.485638 etcd-wrapper[2047]: 5709b4a82c35: Pulling fs layer Dec 13 14:13:14.485638 etcd-wrapper[2047]: d5372e382b07: Pulling fs layer Dec 13 14:13:14.485638 etcd-wrapper[2047]: 9c3b5253dddc: Pulling fs layer Dec 13 14:13:14.485638 etcd-wrapper[2047]: 7a57323f7551: Pulling fs layer Dec 13 14:13:14.485638 etcd-wrapper[2047]: 1069fc2daed1: Waiting Dec 13 14:13:14.485638 etcd-wrapper[2047]: b40161cd83fc: Waiting Dec 13 14:13:14.485638 etcd-wrapper[2047]: 5318d93a3a65: Waiting Dec 13 14:13:14.485638 etcd-wrapper[2047]: 307c1adadb60: Waiting Dec 13 14:13:14.485638 etcd-wrapper[2047]: f9b3c65df792: Waiting Dec 13 14:13:14.485638 etcd-wrapper[2047]: 5709b4a82c35: Waiting Dec 13 14:13:14.485638 etcd-wrapper[2047]: d5372e382b07: Waiting Dec 13 14:13:14.485638 etcd-wrapper[2047]: 9c3b5253dddc: Waiting Dec 13 14:13:14.485638 etcd-wrapper[2047]: 7a57323f7551: Waiting Dec 13 14:13:14.485638 etcd-wrapper[2047]: 0f8b424aa0b9: Waiting Dec 13 14:13:14.485638 etcd-wrapper[2047]: d557676654e5: Waiting Dec 13 14:13:14.485638 etcd-wrapper[2047]: c8022d07192e: Waiting Dec 13 14:13:14.485638 etcd-wrapper[2047]: d858cbc252ad: Waiting Dec 13 14:13:14.741937 etcd-wrapper[2047]: 2ae710cd8bfe: Verifying Checksum Dec 13 14:13:14.742272 etcd-wrapper[2047]: 2ae710cd8bfe: Download complete Dec 13 14:13:14.772063 etcd-wrapper[2047]: d10f96ed9607: Verifying Checksum Dec 13 14:13:14.772343 etcd-wrapper[2047]: d10f96ed9607: Download complete Dec 13 14:13:14.853328 etcd-wrapper[2047]: d462aa345367: Verifying Checksum Dec 13 14:13:14.853707 etcd-wrapper[2047]: d462aa345367: Download complete Dec 13 14:13:14.892985 etcd-wrapper[2047]: d10f96ed9607: Pull complete Dec 13 14:13:14.999789 etcd-wrapper[2047]: 0f8b424aa0b9: Verifying Checksum Dec 13 14:13:14.999789 etcd-wrapper[2047]: 0f8b424aa0b9: Download complete Dec 13 14:13:15.015186 systemd[1]: var-lib-docker-overlay2-757cdc6107291290a0c0b2b9a19f2b8bc95e64e6b8ae302aa84caab77ba61c71-merged.mount: Deactivated successfully. Dec 13 14:13:15.039652 etcd-wrapper[2047]: d557676654e5: Verifying Checksum Dec 13 14:13:15.039652 etcd-wrapper[2047]: d557676654e5: Download complete Dec 13 14:13:15.049533 etcd-wrapper[2047]: 2ae710cd8bfe: Pull complete Dec 13 14:13:15.321820 etcd-wrapper[2047]: d858cbc252ad: Verifying Checksum Dec 13 14:13:15.321820 etcd-wrapper[2047]: d858cbc252ad: Download complete Dec 13 14:13:15.482113 etcd-wrapper[2047]: c8022d07192e: Verifying Checksum Dec 13 14:13:15.482113 etcd-wrapper[2047]: c8022d07192e: Download complete Dec 13 14:13:15.620256 systemd[1]: var-lib-docker-overlay2-26770803c671d03b319a76eb87c37cc8932c234dcfef7bd9142c71485c44dafd-merged.mount: Deactivated successfully. Dec 13 14:13:15.624603 etcd-wrapper[2047]: b40161cd83fc: Verifying Checksum Dec 13 14:13:15.624603 etcd-wrapper[2047]: b40161cd83fc: Download complete Dec 13 14:13:15.679061 etcd-wrapper[2047]: d462aa345367: Pull complete Dec 13 14:13:15.777230 etcd-wrapper[2047]: 0f8b424aa0b9: Pull complete Dec 13 14:13:15.871539 etcd-wrapper[2047]: d557676654e5: Pull complete Dec 13 14:13:15.914357 systemd[1]: var-lib-docker-overlay2-bbe76c2407d4b5b6e661311adff9f0e34cc23ff7cb7f8906d70e3cfda9b3d9a5-merged.mount: Deactivated successfully. Dec 13 14:13:15.918535 etcd-wrapper[2047]: 1069fc2daed1: Verifying Checksum Dec 13 14:13:15.918535 etcd-wrapper[2047]: 1069fc2daed1: Download complete Dec 13 14:13:15.960969 etcd-wrapper[2047]: 307c1adadb60: Verifying Checksum Dec 13 14:13:15.961272 etcd-wrapper[2047]: 307c1adadb60: Download complete Dec 13 14:13:15.967313 systemd[1]: var-lib-docker-overlay2-4ae69fb13b63aca3fc959bc7f882b348d2651fc7906df9aec498675e29070244-merged.mount: Deactivated successfully. Dec 13 14:13:16.000402 etcd-wrapper[2047]: c8022d07192e: Pull complete Dec 13 14:13:16.093338 systemd[1]: var-lib-docker-overlay2-bd6387627e2625ca58160d355b5eab568d1a9568c4335fddf758ffea0e241414-merged.mount: Deactivated successfully. Dec 13 14:13:16.106761 etcd-wrapper[2047]: d858cbc252ad: Pull complete Dec 13 14:13:16.205761 etcd-wrapper[2047]: 1069fc2daed1: Pull complete Dec 13 14:13:16.318186 etcd-wrapper[2047]: b40161cd83fc: Pull complete Dec 13 14:13:16.411141 etcd-wrapper[2047]: 5318d93a3a65: Verifying Checksum Dec 13 14:13:16.411141 etcd-wrapper[2047]: 5318d93a3a65: Download complete Dec 13 14:13:16.422642 etcd-wrapper[2047]: f9b3c65df792: Verifying Checksum Dec 13 14:13:16.422642 etcd-wrapper[2047]: f9b3c65df792: Download complete Dec 13 14:13:16.484348 etcd-wrapper[2047]: 5709b4a82c35: Verifying Checksum Dec 13 14:13:16.557757 etcd-wrapper[2047]: 5318d93a3a65: Pull complete Dec 13 14:13:16.661956 etcd-wrapper[2047]: 307c1adadb60: Pull complete Dec 13 14:13:16.822524 etcd-wrapper[2047]: 7a57323f7551: Verifying Checksum Dec 13 14:13:16.822524 etcd-wrapper[2047]: 7a57323f7551: Download complete Dec 13 14:13:16.894979 etcd-wrapper[2047]: 9c3b5253dddc: Verifying Checksum Dec 13 14:13:16.894979 etcd-wrapper[2047]: 9c3b5253dddc: Download complete Dec 13 14:13:16.914480 systemd[1]: var-lib-docker-overlay2-f73c056ea9c2ed637d03fdc1e8fbd4ea9374369558d9143c71bdb4358407007b-merged.mount: Deactivated successfully. Dec 13 14:13:17.016549 etcd-wrapper[2047]: d5372e382b07: Verifying Checksum Dec 13 14:13:17.016549 etcd-wrapper[2047]: d5372e382b07: Download complete Dec 13 14:13:17.209713 systemd[1]: var-lib-docker-overlay2-30636cd05c706be1ac14a0e84f5dfe0619d5c358d646945297d542bb8b521343-merged.mount: Deactivated successfully. Dec 13 14:13:17.281582 etcd-wrapper[2047]: f9b3c65df792: Pull complete Dec 13 14:13:17.684023 systemd[1]: var-lib-docker-overlay2-c8975c0eeefd51965e7b757802a769d360100fdd7b2a45e5ae74e4279b7c4615-merged.mount: Deactivated successfully. Dec 13 14:13:17.721570 etcd-wrapper[2047]: 5709b4a82c35: Pull complete Dec 13 14:13:18.087270 systemd[1]: var-lib-docker-overlay2-36f2ca4877ee060865f93894f7bc6a921b63a799b75bcb87ba5cf7b4d193a60a-merged.mount: Deactivated successfully. Dec 13 14:13:18.116725 etcd-wrapper[2047]: d5372e382b07: Pull complete Dec 13 14:13:18.197681 systemd[1]: var-lib-docker-overlay2-ddad76453f217cd3bfaa10d3768c70bb79b2c9d9a23a4bd97d71eaff407e00ff-merged.mount: Deactivated successfully. Dec 13 14:13:18.217185 etcd-wrapper[2047]: 9c3b5253dddc: Pull complete Dec 13 14:13:18.264224 amazon-ssm-agent[1757]: 2024-12-13 14:13:18 INFO [MessagingDeliveryService] [Association] No associations on boot. Requerying for associations after 30 seconds. Dec 13 14:13:18.314108 systemd[1]: var-lib-docker-overlay2-63bb5c330d7ca0fe2035b4b74ebd638196e92385d6982181dbf417b0fc0b56c2-merged.mount: Deactivated successfully. Dec 13 14:13:18.334695 etcd-wrapper[2047]: 7a57323f7551: Pull complete Dec 13 14:13:18.347458 etcd-wrapper[2047]: Digest: sha256:d967d98a12dc220a1a290794711dba7eba04b8ce465e12b02383d1bfbb33e159 Dec 13 14:13:18.352438 etcd-wrapper[2047]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.16 Dec 13 14:13:18.516748 env[1734]: time="2024-12-13T14:13:18.516553781Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Dec 13 14:13:18.516748 env[1734]: time="2024-12-13T14:13:18.516638166Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Dec 13 14:13:18.517535 env[1734]: time="2024-12-13T14:13:18.516712978Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Dec 13 14:13:18.517535 env[1734]: time="2024-12-13T14:13:18.517168116Z" level=info msg="starting signal loop" namespace=moby path=/run/containerd/io.containerd.runtime.v2.task/moby/8a4c98bf2b0d86fdc2c24f1d74bbdf0d25e801dcd9770ff8d7f7955d39ab40e8 pid=2185 runtime=io.containerd.runc.v2 Dec 13 14:13:18.538346 systemd[1]: Started docker-8a4c98bf2b0d86fdc2c24f1d74bbdf0d25e801dcd9770ff8d7f7955d39ab40e8.scope. Dec 13 14:13:18.628734 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:18.627981Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} Dec 13 14:13:18.629465 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:18.629177Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"ec2ab2fa919ba3cc00777d8eccc0dea1"} Dec 13 14:13:18.629890 etcd-wrapper[2047]: {"level":"warn","ts":"2024-12-13T14:13:18.629534Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} Dec 13 14:13:18.629890 etcd-wrapper[2047]: {"level":"warn","ts":"2024-12-13T14:13:18.629571Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.16"} Dec 13 14:13:18.629890 etcd-wrapper[2047]: {"level":"warn","ts":"2024-12-13T14:13:18.629590Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} Dec 13 14:13:18.629890 etcd-wrapper[2047]: {"level":"warn","ts":"2024-12-13T14:13:18.629605Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} Dec 13 14:13:18.630793 etcd-wrapper[2047]: {"level":"warn","ts":"2024-12-13T14:13:18.630457Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Dec 13 14:13:18.630793 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:18.630525Z","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.24.120:2379"]} Dec 13 14:13:18.631278 etcd-wrapper[2047]: {"level":"warn","ts":"2024-12-13T14:13:18.631053Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Dec 13 14:13:18.631584 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:18.631106Z","caller":"embed/etcd.go:128","msg":"configuring peer listeners","listen-peer-urls":["http://localhost:2380"]} Dec 13 14:13:18.632430 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:18.632083Z","caller":"embed/etcd.go:136","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} Dec 13 14:13:18.632919 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:18.632594Z","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":"ec2ab2fa919ba3cc00777d8eccc0dea1","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.24.120:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"ec2ab2fa919ba3cc00777d8eccc0dea1=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"} Dec 13 14:13:18.635866 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:18.635520Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.149979ms"} Dec 13 14:13:18.640120 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:18.639742Z","caller":"etcdserver/raft.go:505","msg":"starting local member","local-member-id":"8e9e05c52164694d","cluster-id":"cdf818194e3a8c32"} Dec 13 14:13:18.640655 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:18.640114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=()"} Dec 13 14:13:18.640655 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:18.640186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 0"} Dec 13 14:13:18.640655 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:18.640211Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8e9e05c52164694d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} Dec 13 14:13:18.640655 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:18.640231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 1"} Dec 13 14:13:18.640655 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:18.640294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Dec 13 14:13:18.643967 etcd-wrapper[2047]: {"level":"warn","ts":"2024-12-13T14:13:18.643633Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} Dec 13 14:13:18.646863 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:18.646363Z","caller":"mvcc/kvstore.go:423","msg":"kvstore restored","current-rev":1} Dec 13 14:13:18.647916 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:18.647599Z","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"} Dec 13 14:13:18.648942 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:18.648646Z","caller":"etcdserver/server.go:873","msg":"starting etcd server","local-member-id":"8e9e05c52164694d","local-server-version":"3.5.16","cluster-version":"to_be_decided"} Dec 13 14:13:18.649420 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:18.649153Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Dec 13 14:13:18.650534 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:18.650166Z","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"} Dec 13 14:13:18.651167 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:18.650907Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} Dec 13 14:13:18.651578 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:18.651322Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} Dec 13 14:13:18.651578 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:18.651404Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} Dec 13 14:13:18.652423 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:18.652125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Dec 13 14:13:18.653008 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:18.652753Z","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"]} Dec 13 14:13:18.653815 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:18.653516Z","caller":"embed/etcd.go:600","msg":"serving peer traffic","address":"127.0.0.1:2380"} Dec 13 14:13:18.653815 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:18.653611Z","caller":"embed/etcd.go:572","msg":"cmux::serve","address":"127.0.0.1:2380"} Dec 13 14:13:18.654342 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:18.653540Z","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.24.120:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} Dec 13 14:13:18.850332 systemd[1]: Created slice system-sshd.slice. Dec 13 14:13:18.853490 systemd[1]: Started sshd@0-172.31.24.120:22-139.178.89.65:38528.service. Dec 13 14:13:19.049937 sshd[2223]: Accepted publickey for core from 139.178.89.65 port 38528 ssh2: RSA SHA256:07jB2DPJgjjhgg3L8Uh349EZ0zHZFrUiRWNbK+Fdo0Q Dec 13 14:13:19.055011 sshd[2223]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Dec 13 14:13:19.077032 systemd[1]: Created slice user-500.slice. Dec 13 14:13:19.079564 systemd[1]: Starting user-runtime-dir@500.service... Dec 13 14:13:19.092094 systemd-logind[1725]: New session 1 of user core. Dec 13 14:13:19.103180 systemd[1]: Finished user-runtime-dir@500.service. Dec 13 14:13:19.107578 systemd[1]: Starting user@500.service... Dec 13 14:13:19.115536 (systemd)[2226]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Dec 13 14:13:19.304726 systemd[2226]: Queued start job for default target default.target. Dec 13 14:13:19.306308 systemd[2226]: Reached target paths.target. Dec 13 14:13:19.306546 systemd[2226]: Reached target sockets.target. Dec 13 14:13:19.307030 systemd[2226]: Reached target timers.target. Dec 13 14:13:19.307206 systemd[2226]: Reached target basic.target. Dec 13 14:13:19.307430 systemd[2226]: Reached target default.target. Dec 13 14:13:19.307518 systemd[1]: Started user@500.service. Dec 13 14:13:19.307960 systemd[2226]: Startup finished in 180ms. Dec 13 14:13:19.311127 systemd[1]: Started session-1.scope. Dec 13 14:13:19.441583 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:19.440945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d is starting a new election at term 1"} Dec 13 14:13:19.441583 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:19.440994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became pre-candidate at term 1"} Dec 13 14:13:19.441583 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:19.441042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgPreVoteResp from 8e9e05c52164694d at term 1"} Dec 13 14:13:19.441583 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:19.441067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became candidate at term 2"} Dec 13 14:13:19.441583 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:19.441083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 2"} Dec 13 14:13:19.441583 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:19.441104Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became leader at term 2"} Dec 13 14:13:19.441583 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:19.441121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 2"} Dec 13 14:13:19.446832 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:19.441868Z","caller":"etcdserver/server.go:2651","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} Dec 13 14:13:19.446832 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:19.442002Z","caller":"etcdserver/server.go:2140","msg":"published local member to cluster through raft","local-member-id":"8e9e05c52164694d","local-member-attributes":"{Name:ec2ab2fa919ba3cc00777d8eccc0dea1 ClientURLs:[http://172.31.24.120:2379]}","request-path":"/0/members/8e9e05c52164694d/attributes","cluster-id":"cdf818194e3a8c32","publish-timeout":"7s"} Dec 13 14:13:19.446832 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:19.442044Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} Dec 13 14:13:19.446832 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:19.442558Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","cluster-version":"3.5"} Dec 13 14:13:19.446832 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:19.442675Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} Dec 13 14:13:19.446832 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:19.442711Z","caller":"etcdserver/server.go:2675","msg":"cluster version is updated","cluster-version":"3.5"} Dec 13 14:13:19.446832 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:19.442967Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Dec 13 14:13:19.446832 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:19.443857Z","caller":"embed/serve.go:187","msg":"serving client traffic insecurely; this is strongly discouraged!","traffic":"grpc+http","address":"[::]:2379"} Dec 13 14:13:19.446832 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:19.444613Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} Dec 13 14:13:19.445201 systemd[1]: Started etcd-member.service. Dec 13 14:13:19.445814 systemd[1]: Reached target multi-user.target. Dec 13 14:13:19.448437 systemd[1]: Starting systemd-update-utmp-runlevel.service... Dec 13 14:13:19.475896 etcd-wrapper[2047]: {"level":"info","ts":"2024-12-13T14:13:19.445021Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} Dec 13 14:13:19.466213 systemd[1]: Started sshd@1-172.31.24.120:22-139.178.89.65:38530.service. Dec 13 14:13:19.478741 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Dec 13 14:13:19.479165 systemd[1]: Finished systemd-update-utmp-runlevel.service. Dec 13 14:13:19.482802 systemd[1]: Startup finished in 1.147s (kernel) + 6.480s (initrd) + 17.408s (userspace) = 25.037s. Dec 13 14:13:19.642766 sshd[2239]: Accepted publickey for core from 139.178.89.65 port 38530 ssh2: RSA SHA256:07jB2DPJgjjhgg3L8Uh349EZ0zHZFrUiRWNbK+Fdo0Q Dec 13 14:13:19.645151 sshd[2239]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Dec 13 14:13:19.653892 systemd-logind[1725]: New session 2 of user core. Dec 13 14:13:19.654772 systemd[1]: Started session-2.scope. Dec 13 14:13:19.791346 sshd[2239]: pam_unix(sshd:session): session closed for user core Dec 13 14:13:19.797423 systemd[1]: sshd@1-172.31.24.120:22-139.178.89.65:38530.service: Deactivated successfully. Dec 13 14:13:19.798551 systemd-logind[1725]: Session 2 logged out. Waiting for processes to exit. Dec 13 14:13:19.798784 systemd[1]: session-2.scope: Deactivated successfully. Dec 13 14:13:19.800878 systemd-logind[1725]: Removed session 2. Dec 13 14:13:19.820026 systemd[1]: Started sshd@2-172.31.24.120:22-139.178.89.65:38544.service. Dec 13 14:13:19.992839 sshd[2245]: Accepted publickey for core from 139.178.89.65 port 38544 ssh2: RSA SHA256:07jB2DPJgjjhgg3L8Uh349EZ0zHZFrUiRWNbK+Fdo0Q Dec 13 14:13:19.996372 sshd[2245]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Dec 13 14:13:20.004610 systemd-logind[1725]: New session 3 of user core. Dec 13 14:13:20.005716 systemd[1]: Started session-3.scope. Dec 13 14:13:20.129956 sshd[2245]: pam_unix(sshd:session): session closed for user core Dec 13 14:13:20.134726 systemd[1]: sshd@2-172.31.24.120:22-139.178.89.65:38544.service: Deactivated successfully. Dec 13 14:13:20.136005 systemd[1]: session-3.scope: Deactivated successfully. Dec 13 14:13:20.137205 systemd-logind[1725]: Session 3 logged out. Waiting for processes to exit. Dec 13 14:13:20.139536 systemd-logind[1725]: Removed session 3. Dec 13 14:13:20.157256 systemd[1]: Started sshd@3-172.31.24.120:22-139.178.89.65:38558.service. Dec 13 14:13:20.324428 sshd[2251]: Accepted publickey for core from 139.178.89.65 port 38558 ssh2: RSA SHA256:07jB2DPJgjjhgg3L8Uh349EZ0zHZFrUiRWNbK+Fdo0Q Dec 13 14:13:20.327227 sshd[2251]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Dec 13 14:13:20.334912 systemd-logind[1725]: New session 4 of user core. Dec 13 14:13:20.335808 systemd[1]: Started session-4.scope. Dec 13 14:13:20.464852 sshd[2251]: pam_unix(sshd:session): session closed for user core Dec 13 14:13:20.469702 systemd-logind[1725]: Session 4 logged out. Waiting for processes to exit. Dec 13 14:13:20.470276 systemd[1]: sshd@3-172.31.24.120:22-139.178.89.65:38558.service: Deactivated successfully. Dec 13 14:13:20.471479 systemd[1]: session-4.scope: Deactivated successfully. Dec 13 14:13:20.473157 systemd-logind[1725]: Removed session 4. Dec 13 14:13:20.495306 systemd[1]: Started sshd@4-172.31.24.120:22-139.178.89.65:38574.service. Dec 13 14:13:20.670318 sshd[2257]: Accepted publickey for core from 139.178.89.65 port 38574 ssh2: RSA SHA256:07jB2DPJgjjhgg3L8Uh349EZ0zHZFrUiRWNbK+Fdo0Q Dec 13 14:13:20.672766 sshd[2257]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Dec 13 14:13:20.681418 systemd[1]: Started session-5.scope. Dec 13 14:13:20.682213 systemd-logind[1725]: New session 5 of user core. Dec 13 14:13:20.807601 sudo[2260]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Dec 13 14:13:20.808146 sudo[2260]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Dec 13 14:13:20.822937 dbus-daemon[1719]: avc: received setenforce notice (enforcing=1) Dec 13 14:13:20.825648 sudo[2260]: pam_unix(sudo:session): session closed for user root Dec 13 14:13:20.851752 sshd[2257]: pam_unix(sshd:session): session closed for user core Dec 13 14:13:20.857044 systemd[1]: session-5.scope: Deactivated successfully. Dec 13 14:13:20.858287 systemd[1]: sshd@4-172.31.24.120:22-139.178.89.65:38574.service: Deactivated successfully. Dec 13 14:13:20.860209 systemd-logind[1725]: Session 5 logged out. Waiting for processes to exit. Dec 13 14:13:20.862487 systemd-logind[1725]: Removed session 5. Dec 13 14:13:20.882131 systemd[1]: Started sshd@5-172.31.24.120:22-139.178.89.65:38582.service. Dec 13 14:13:21.055938 sshd[2264]: Accepted publickey for core from 139.178.89.65 port 38582 ssh2: RSA SHA256:07jB2DPJgjjhgg3L8Uh349EZ0zHZFrUiRWNbK+Fdo0Q Dec 13 14:13:21.059062 sshd[2264]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Dec 13 14:13:21.068280 systemd[1]: Started session-6.scope. Dec 13 14:13:21.069782 systemd-logind[1725]: New session 6 of user core. Dec 13 14:13:21.177690 sudo[2268]: 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 Dec 13 14:13:21.178699 sudo[2268]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Dec 13 14:13:21.183987 sudo[2268]: pam_unix(sudo:session): session closed for user root Dec 13 14:13:21.193409 sudo[2267]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/systemctl restart audit-rules Dec 13 14:13:21.193956 sudo[2267]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Dec 13 14:13:21.213167 systemd[1]: Stopping audit-rules.service... Dec 13 14:13:21.214000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Dec 13 14:13:21.217380 kernel: kauditd_printk_skb: 71 callbacks suppressed Dec 13 14:13:21.217503 kernel: audit: type=1305 audit(1734099201.214:166): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Dec 13 14:13:21.218340 auditctl[2271]: No rules Dec 13 14:13:21.214000 audit[2271]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=fffff6c2a970 a2=420 a3=0 items=0 ppid=1 pid=2271 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditctl" exe="/usr/sbin/auditctl" subj=system_u:system_r:kernel_t:s0 key=(null) Dec 13 14:13:21.219527 systemd[1]: audit-rules.service: Deactivated successfully. Dec 13 14:13:21.219941 systemd[1]: Stopped audit-rules.service. Dec 13 14:13:21.232785 kernel: audit: type=1300 audit(1734099201.214:166): arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=fffff6c2a970 a2=420 a3=0 items=0 ppid=1 pid=2271 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditctl" exe="/usr/sbin/auditctl" subj=system_u:system_r:kernel_t:s0 key=(null) Dec 13 14:13:21.234783 systemd[1]: Starting audit-rules.service... Dec 13 14:13:21.214000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D44 Dec 13 14:13:21.240259 kernel: audit: type=1327 audit(1734099201.214:166): proctitle=2F7362696E2F617564697463746C002D44 Dec 13 14:13:21.219000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=audit-rules comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:21.248045 kernel: audit: type=1131 audit(1734099201.219:167): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=audit-rules comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:21.268548 augenrules[2288]: No rules Dec 13 14:13:21.269000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=audit-rules comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:21.270231 systemd[1]: Finished audit-rules.service. Dec 13 14:13:21.272129 sudo[2267]: pam_unix(sudo:session): session closed for user root Dec 13 14:13:21.271000 audit[2267]: USER_END pid=2267 uid=500 auid=500 ses=6 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Dec 13 14:13:21.288924 kernel: audit: type=1130 audit(1734099201.269:168): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=audit-rules comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:21.288987 kernel: audit: type=1106 audit(1734099201.271:169): pid=2267 uid=500 auid=500 ses=6 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Dec 13 14:13:21.271000 audit[2267]: CRED_DISP pid=2267 uid=500 auid=500 ses=6 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Dec 13 14:13:21.296527 kernel: audit: type=1104 audit(1734099201.271:170): pid=2267 uid=500 auid=500 ses=6 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Dec 13 14:13:21.303094 sshd[2264]: pam_unix(sshd:session): session closed for user core Dec 13 14:13:21.304000 audit[2264]: USER_END pid=2264 uid=0 auid=500 ses=6 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=139.178.89.65 addr=139.178.89.65 terminal=ssh res=success' Dec 13 14:13:21.304000 audit[2264]: CRED_DISP pid=2264 uid=0 auid=500 ses=6 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=139.178.89.65 addr=139.178.89.65 terminal=ssh res=success' Dec 13 14:13:21.326681 kernel: audit: type=1106 audit(1734099201.304:171): pid=2264 uid=0 auid=500 ses=6 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=139.178.89.65 addr=139.178.89.65 terminal=ssh res=success' Dec 13 14:13:21.326799 kernel: audit: type=1104 audit(1734099201.304:172): pid=2264 uid=0 auid=500 ses=6 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=139.178.89.65 addr=139.178.89.65 terminal=ssh res=success' Dec 13 14:13:21.317900 systemd[1]: sshd@5-172.31.24.120:22-139.178.89.65:38582.service: Deactivated successfully. Dec 13 14:13:21.319225 systemd[1]: session-6.scope: Deactivated successfully. Dec 13 14:13:21.316000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@5-172.31.24.120:22-139.178.89.65:38582 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:21.338786 kernel: audit: type=1131 audit(1734099201.316:173): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@5-172.31.24.120:22-139.178.89.65:38582 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:21.334588 systemd[1]: Started sshd@6-172.31.24.120:22-139.178.89.65:38592.service. Dec 13 14:13:21.337415 systemd-logind[1725]: Session 6 logged out. Waiting for processes to exit. Dec 13 14:13:21.336000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@6-172.31.24.120:22-139.178.89.65:38592 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:21.342858 systemd-logind[1725]: Removed session 6. Dec 13 14:13:21.507000 audit[2294]: USER_ACCT pid=2294 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:accounting grantors=pam_access,pam_unix,pam_faillock,pam_permit acct="core" exe="/usr/sbin/sshd" hostname=139.178.89.65 addr=139.178.89.65 terminal=ssh res=success' Dec 13 14:13:21.508848 sshd[2294]: Accepted publickey for core from 139.178.89.65 port 38592 ssh2: RSA SHA256:07jB2DPJgjjhgg3L8Uh349EZ0zHZFrUiRWNbK+Fdo0Q Dec 13 14:13:21.509000 audit[2294]: CRED_ACQ pid=2294 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=139.178.89.65 addr=139.178.89.65 terminal=ssh res=success' Dec 13 14:13:21.510000 audit[2294]: SYSCALL arch=c00000b7 syscall=64 success=yes exit=3 a0=5 a1=ffffeef64800 a2=3 a3=1 items=0 ppid=1 pid=2294 auid=500 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=7 comm="sshd" exe="/usr/sbin/sshd" subj=system_u:system_r:kernel_t:s0 key=(null) Dec 13 14:13:21.510000 audit: PROCTITLE proctitle=737368643A20636F7265205B707269765D Dec 13 14:13:21.511959 sshd[2294]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Dec 13 14:13:21.519759 systemd-logind[1725]: New session 7 of user core. Dec 13 14:13:21.520732 systemd[1]: Started session-7.scope. Dec 13 14:13:21.529000 audit[2294]: USER_START pid=2294 uid=0 auid=500 ses=7 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_open grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=139.178.89.65 addr=139.178.89.65 terminal=ssh res=success' Dec 13 14:13:21.531000 audit[2296]: CRED_ACQ pid=2296 uid=0 auid=500 ses=7 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=139.178.89.65 addr=139.178.89.65 terminal=ssh res=success' Dec 13 14:13:21.645982 sshd[2294]: pam_unix(sshd:session): session closed for user core Dec 13 14:13:21.646000 audit[2294]: USER_END pid=2294 uid=0 auid=500 ses=7 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=139.178.89.65 addr=139.178.89.65 terminal=ssh res=success' Dec 13 14:13:21.647000 audit[2294]: CRED_DISP pid=2294 uid=0 auid=500 ses=7 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=139.178.89.65 addr=139.178.89.65 terminal=ssh res=success' Dec 13 14:13:21.651783 systemd[1]: sshd@6-172.31.24.120:22-139.178.89.65:38592.service: Deactivated successfully. Dec 13 14:13:21.651893 systemd-logind[1725]: Session 7 logged out. Waiting for processes to exit. Dec 13 14:13:21.650000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@6-172.31.24.120:22-139.178.89.65:38592 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:21.653145 systemd[1]: session-7.scope: Deactivated successfully. Dec 13 14:13:21.655242 systemd-logind[1725]: Removed session 7. Dec 13 14:13:21.673819 systemd[1]: Started sshd@7-172.31.24.120:22-139.178.89.65:38594.service. Dec 13 14:13:21.672000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@7-172.31.24.120:22-139.178.89.65:38594 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:21.848000 audit[2302]: USER_ACCT pid=2302 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:accounting grantors=pam_access,pam_unix,pam_faillock,pam_permit acct="core" exe="/usr/sbin/sshd" hostname=139.178.89.65 addr=139.178.89.65 terminal=ssh res=success' Dec 13 14:13:21.849909 sshd[2302]: Accepted publickey for core from 139.178.89.65 port 38594 ssh2: RSA SHA256:07jB2DPJgjjhgg3L8Uh349EZ0zHZFrUiRWNbK+Fdo0Q Dec 13 14:13:21.851000 audit[2302]: CRED_ACQ pid=2302 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=139.178.89.65 addr=139.178.89.65 terminal=ssh res=success' Dec 13 14:13:21.851000 audit[2302]: SYSCALL arch=c00000b7 syscall=64 success=yes exit=3 a0=5 a1=ffffe96cd3c0 a2=3 a3=1 items=0 ppid=1 pid=2302 auid=500 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=8 comm="sshd" exe="/usr/sbin/sshd" subj=system_u:system_r:kernel_t:s0 key=(null) Dec 13 14:13:21.851000 audit: PROCTITLE proctitle=737368643A20636F7265205B707269765D Dec 13 14:13:21.853724 sshd[2302]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Dec 13 14:13:21.862423 systemd-logind[1725]: New session 8 of user core. Dec 13 14:13:21.863578 systemd[1]: Started session-8.scope. Dec 13 14:13:21.872000 audit[2302]: USER_START pid=2302 uid=0 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_open grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=139.178.89.65 addr=139.178.89.65 terminal=ssh res=success' Dec 13 14:13:21.875000 audit[2304]: CRED_ACQ pid=2304 uid=0 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=139.178.89.65 addr=139.178.89.65 terminal=ssh res=success' Dec 13 14:13:22.185024 sshd[2302]: pam_unix(sshd:session): session closed for user core Dec 13 14:13:22.186000 audit[2302]: USER_END pid=2302 uid=0 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=139.178.89.65 addr=139.178.89.65 terminal=ssh res=success' Dec 13 14:13:22.187000 audit[2302]: CRED_DISP pid=2302 uid=0 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=139.178.89.65 addr=139.178.89.65 terminal=ssh res=success' Dec 13 14:13:22.190810 systemd-logind[1725]: Session 8 logged out. Waiting for processes to exit. Dec 13 14:13:22.191237 systemd[1]: sshd@7-172.31.24.120:22-139.178.89.65:38594.service: Deactivated successfully. Dec 13 14:13:22.192652 systemd[1]: session-8.scope: Deactivated successfully. Dec 13 14:13:22.190000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@7-172.31.24.120:22-139.178.89.65:38594 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:22.194175 systemd-logind[1725]: Removed session 8. Dec 13 14:13:40.498575 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Dec 13 14:13:40.501137 kernel: kauditd_printk_skb: 22 callbacks suppressed Dec 13 14:13:40.501213 kernel: audit: type=1131 audit(1734099220.497:192): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-hostnamed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:40.497000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-hostnamed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:40.532000 audit: BPF prog-id=34 op=UNLOAD Dec 13 14:13:40.532000 audit: BPF prog-id=33 op=UNLOAD Dec 13 14:13:40.538461 kernel: audit: type=1334 audit(1734099220.532:193): prog-id=34 op=UNLOAD Dec 13 14:13:40.538522 kernel: audit: type=1334 audit(1734099220.532:194): prog-id=33 op=UNLOAD Dec 13 14:13:40.538577 kernel: audit: type=1334 audit(1734099220.532:195): prog-id=32 op=UNLOAD Dec 13 14:13:40.532000 audit: BPF prog-id=32 op=UNLOAD Dec 13 14:13:44.556007 amazon-ssm-agent[1757]: 2024-12-13 14:13:44 INFO [HealthCheck] HealthCheck reporting agent health. Dec 13 14:13:48.283775 amazon-ssm-agent[1757]: 2024-12-13 14:13:48 INFO [MessagingDeliveryService] [Association] Schedule manager refreshed with 0 associations, 0 new associations associated Dec 13 14:13:54.459810 update_engine[1726]: I1213 14:13:54.459740 1726 update_attempter.cc:509] Updating boot flags... Dec 13 14:16:01.536046 update_engine[1726]: I1213 14:16:01.535984 1726 prefs.cc:52] certificate-report-to-send-update not present in /var/lib/update_engine/prefs Dec 13 14:16:01.536046 update_engine[1726]: I1213 14:16:01.536049 1726 prefs.cc:52] certificate-report-to-send-download not present in /var/lib/update_engine/prefs Dec 13 14:16:01.536911 update_engine[1726]: I1213 14:16:01.536311 1726 prefs.cc:52] aleph-version not present in /var/lib/update_engine/prefs Dec 13 14:16:01.537313 update_engine[1726]: I1213 14:16:01.537271 1726 omaha_request_params.cc:62] Current group set to lts Dec 13 14:16:01.537811 update_engine[1726]: I1213 14:16:01.537503 1726 update_attempter.cc:499] Already updated boot flags. Skipping. Dec 13 14:16:01.537811 update_engine[1726]: I1213 14:16:01.537526 1726 update_attempter.cc:643] Scheduling an action processor start. Dec 13 14:16:01.537811 update_engine[1726]: I1213 14:16:01.537566 1726 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Dec 13 14:16:01.537811 update_engine[1726]: I1213 14:16:01.537621 1726 prefs.cc:52] previous-version not present in /var/lib/update_engine/prefs Dec 13 14:16:01.539413 update_engine[1726]: I1213 14:16:01.539356 1726 omaha_request_action.cc:270] Posting an Omaha request to disabled Dec 13 14:16:01.539413 update_engine[1726]: I1213 14:16:01.539404 1726 omaha_request_action.cc:271] Request: Dec 13 14:16:01.539413 update_engine[1726]: Dec 13 14:16:01.539413 update_engine[1726]: Dec 13 14:16:01.539413 update_engine[1726]: Dec 13 14:16:01.539413 update_engine[1726]: Dec 13 14:16:01.539413 update_engine[1726]: Dec 13 14:16:01.539413 update_engine[1726]: Dec 13 14:16:01.539413 update_engine[1726]: Dec 13 14:16:01.539413 update_engine[1726]: Dec 13 14:16:01.540500 update_engine[1726]: I1213 14:16:01.539422 1726 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Dec 13 14:16:01.540701 locksmithd[1781]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_CHECKING_FOR_UPDATE" NewVersion=0.0.0 NewSize=0 Dec 13 14:16:01.544792 update_engine[1726]: I1213 14:16:01.544731 1726 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Dec 13 14:16:01.545167 update_engine[1726]: I1213 14:16:01.545123 1726 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Dec 13 14:16:01.565794 update_engine[1726]: E1213 14:16:01.565743 1726 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Dec 13 14:16:01.565940 update_engine[1726]: I1213 14:16:01.565888 1726 libcurl_http_fetcher.cc:283] No HTTP response, retry 1 Dec 13 14:16:11.456496 update_engine[1726]: I1213 14:16:11.455857 1726 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Dec 13 14:16:11.456496 update_engine[1726]: I1213 14:16:11.456148 1726 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Dec 13 14:16:11.456496 update_engine[1726]: I1213 14:16:11.456377 1726 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Dec 13 14:16:11.457626 update_engine[1726]: E1213 14:16:11.457470 1726 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Dec 13 14:16:11.457626 update_engine[1726]: I1213 14:16:11.457592 1726 libcurl_http_fetcher.cc:283] No HTTP response, retry 2 Dec 13 14:16:21.457258 update_engine[1726]: I1213 14:16:21.456696 1726 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Dec 13 14:16:21.457258 update_engine[1726]: I1213 14:16:21.456972 1726 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Dec 13 14:16:21.457258 update_engine[1726]: I1213 14:16:21.457194 1726 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Dec 13 14:16:21.458394 update_engine[1726]: E1213 14:16:21.458235 1726 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Dec 13 14:16:21.458394 update_engine[1726]: I1213 14:16:21.458356 1726 libcurl_http_fetcher.cc:283] No HTTP response, retry 3 Dec 13 14:16:31.456730 update_engine[1726]: I1213 14:16:31.456444 1726 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Dec 13 14:16:31.457284 update_engine[1726]: I1213 14:16:31.456787 1726 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Dec 13 14:16:31.457284 update_engine[1726]: I1213 14:16:31.457028 1726 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Dec 13 14:16:31.457543 update_engine[1726]: E1213 14:16:31.457497 1726 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Dec 13 14:16:31.457653 update_engine[1726]: I1213 14:16:31.457633 1726 libcurl_http_fetcher.cc:297] Transfer resulted in an error (0), 0 bytes downloaded Dec 13 14:16:31.457653 update_engine[1726]: I1213 14:16:31.457648 1726 omaha_request_action.cc:621] Omaha request response: Dec 13 14:16:31.457818 update_engine[1726]: E1213 14:16:31.457783 1726 omaha_request_action.cc:640] Omaha request network transfer failed. Dec 13 14:16:31.457818 update_engine[1726]: I1213 14:16:31.457811 1726 action_processor.cc:68] ActionProcessor::ActionComplete: OmahaRequestAction action failed. Aborting processing. Dec 13 14:16:31.457937 update_engine[1726]: I1213 14:16:31.457823 1726 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Dec 13 14:16:31.457937 update_engine[1726]: I1213 14:16:31.457830 1726 update_attempter.cc:306] Processing Done. Dec 13 14:16:31.457937 update_engine[1726]: E1213 14:16:31.457852 1726 update_attempter.cc:619] Update failed. Dec 13 14:16:31.457937 update_engine[1726]: I1213 14:16:31.457861 1726 utils.cc:600] Converting error code 2000 to kActionCodeOmahaErrorInHTTPResponse Dec 13 14:16:31.457937 update_engine[1726]: I1213 14:16:31.457870 1726 payload_state.cc:97] Updating payload state for error code: 37 (kActionCodeOmahaErrorInHTTPResponse) Dec 13 14:16:31.457937 update_engine[1726]: I1213 14:16:31.457880 1726 payload_state.cc:103] Ignoring failures until we get a valid Omaha response. Dec 13 14:16:31.458274 update_engine[1726]: I1213 14:16:31.457988 1726 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Dec 13 14:16:31.458274 update_engine[1726]: I1213 14:16:31.458019 1726 omaha_request_action.cc:270] Posting an Omaha request to disabled Dec 13 14:16:31.458274 update_engine[1726]: I1213 14:16:31.458030 1726 omaha_request_action.cc:271] Request: Dec 13 14:16:31.458274 update_engine[1726]: Dec 13 14:16:31.458274 update_engine[1726]: Dec 13 14:16:31.458274 update_engine[1726]: Dec 13 14:16:31.458274 update_engine[1726]: Dec 13 14:16:31.458274 update_engine[1726]: Dec 13 14:16:31.458274 update_engine[1726]: Dec 13 14:16:31.458274 update_engine[1726]: I1213 14:16:31.458040 1726 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Dec 13 14:16:31.458274 update_engine[1726]: I1213 14:16:31.458250 1726 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Dec 13 14:16:31.459400 update_engine[1726]: I1213 14:16:31.458520 1726 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Dec 13 14:16:31.459400 update_engine[1726]: E1213 14:16:31.458848 1726 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Dec 13 14:16:31.459400 update_engine[1726]: I1213 14:16:31.458998 1726 libcurl_http_fetcher.cc:297] Transfer resulted in an error (0), 0 bytes downloaded Dec 13 14:16:31.459400 update_engine[1726]: I1213 14:16:31.459013 1726 omaha_request_action.cc:621] Omaha request response: Dec 13 14:16:31.459400 update_engine[1726]: I1213 14:16:31.459024 1726 action_processor.cc:65] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Dec 13 14:16:31.459400 update_engine[1726]: I1213 14:16:31.459033 1726 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Dec 13 14:16:31.459400 update_engine[1726]: I1213 14:16:31.459043 1726 update_attempter.cc:306] Processing Done. Dec 13 14:16:31.459400 update_engine[1726]: I1213 14:16:31.459053 1726 update_attempter.cc:310] Error event sent. Dec 13 14:16:31.459400 update_engine[1726]: I1213 14:16:31.459072 1726 update_check_scheduler.cc:74] Next update check in 47m49s Dec 13 14:16:31.459923 locksmithd[1781]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_REPORTING_ERROR_EVENT" NewVersion=0.0.0 NewSize=0 Dec 13 14:16:31.460379 locksmithd[1781]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_IDLE" NewVersion=0.0.0 NewSize=0