Jul 12 00:23:56.991880 kernel: Booting Linux on physical CPU 0x0000000000 [0x410fd083] Jul 12 00:23:56.991917 kernel: Linux version 5.15.186-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 Jul 11 23:15:18 -00 2025 Jul 12 00:23:56.991940 kernel: efi: EFI v2.70 by EDK II Jul 12 00:23:56.991954 kernel: efi: SMBIOS=0x7bed0000 SMBIOS 3.0=0x7beb0000 ACPI=0x786e0000 ACPI 2.0=0x786e0014 MEMATTR=0x7affea98 MEMRESERVE=0x716fcf98 Jul 12 00:23:56.991968 kernel: ACPI: Early table checksum verification disabled Jul 12 00:23:56.991981 kernel: ACPI: RSDP 0x00000000786E0014 000024 (v02 AMAZON) Jul 12 00:23:56.991997 kernel: ACPI: XSDT 0x00000000786D00E8 000064 (v01 AMAZON AMZNFACP 00000001 01000013) Jul 12 00:23:56.992011 kernel: ACPI: FACP 0x00000000786B0000 000114 (v06 AMAZON AMZNFACP 00000001 AMZN 00000001) Jul 12 00:23:56.992025 kernel: ACPI: DSDT 0x0000000078640000 00159D (v02 AMAZON AMZNDSDT 00000001 INTL 20160527) Jul 12 00:23:56.997357 kernel: ACPI: APIC 0x00000000786C0000 000108 (v04 AMAZON AMZNAPIC 00000001 AMZN 00000001) Jul 12 00:23:56.997382 kernel: ACPI: SPCR 0x00000000786A0000 000050 (v02 AMAZON AMZNSPCR 00000001 AMZN 00000001) Jul 12 00:23:56.997396 kernel: ACPI: GTDT 0x0000000078690000 000060 (v02 AMAZON AMZNGTDT 00000001 AMZN 00000001) Jul 12 00:23:56.997410 kernel: ACPI: MCFG 0x0000000078680000 00003C (v02 AMAZON AMZNMCFG 00000001 AMZN 00000001) Jul 12 00:23:56.997424 kernel: ACPI: SLIT 0x0000000078670000 00002D (v01 AMAZON AMZNSLIT 00000001 AMZN 00000001) Jul 12 00:23:56.997440 kernel: ACPI: IORT 0x0000000078660000 000078 (v01 AMAZON AMZNIORT 00000001 AMZN 00000001) Jul 12 00:23:56.997459 kernel: ACPI: PPTT 0x0000000078650000 0000EC (v01 AMAZON AMZNPPTT 00000001 AMZN 00000001) Jul 12 00:23:56.997473 kernel: ACPI: SPCR: console: uart,mmio,0x90a0000,115200 Jul 12 00:23:56.997488 kernel: earlycon: uart0 at MMIO 0x00000000090a0000 (options '115200') Jul 12 00:23:56.997502 kernel: printk: bootconsole [uart0] enabled Jul 12 00:23:56.997516 kernel: NUMA: Failed to initialise from firmware Jul 12 00:23:56.997531 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000004b5ffffff] Jul 12 00:23:56.997546 kernel: NUMA: NODE_DATA [mem 0x4b5843900-0x4b5848fff] Jul 12 00:23:56.997560 kernel: Zone ranges: Jul 12 00:23:56.997575 kernel: DMA [mem 0x0000000040000000-0x00000000ffffffff] Jul 12 00:23:56.997589 kernel: DMA32 empty Jul 12 00:23:56.997603 kernel: Normal [mem 0x0000000100000000-0x00000004b5ffffff] Jul 12 00:23:56.997621 kernel: Movable zone start for each node Jul 12 00:23:56.997635 kernel: Early memory node ranges Jul 12 00:23:56.997649 kernel: node 0: [mem 0x0000000040000000-0x000000007862ffff] Jul 12 00:23:56.997663 kernel: node 0: [mem 0x0000000078630000-0x000000007863ffff] Jul 12 00:23:56.997677 kernel: node 0: [mem 0x0000000078640000-0x00000000786effff] Jul 12 00:23:56.997691 kernel: node 0: [mem 0x00000000786f0000-0x000000007872ffff] Jul 12 00:23:56.997705 kernel: node 0: [mem 0x0000000078730000-0x000000007bbfffff] Jul 12 00:23:56.997741 kernel: node 0: [mem 0x000000007bc00000-0x000000007bfdffff] Jul 12 00:23:56.997760 kernel: node 0: [mem 0x000000007bfe0000-0x000000007fffffff] Jul 12 00:23:56.997775 kernel: node 0: [mem 0x0000000400000000-0x00000004b5ffffff] Jul 12 00:23:56.997789 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000004b5ffffff] Jul 12 00:23:56.997804 kernel: On node 0, zone Normal: 8192 pages in unavailable ranges Jul 12 00:23:56.997823 kernel: psci: probing for conduit method from ACPI. Jul 12 00:23:56.997838 kernel: psci: PSCIv1.0 detected in firmware. Jul 12 00:23:56.997859 kernel: psci: Using standard PSCI v0.2 function IDs Jul 12 00:23:56.997874 kernel: psci: Trusted OS migration not required Jul 12 00:23:56.997889 kernel: psci: SMC Calling Convention v1.1 Jul 12 00:23:56.997908 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000001) Jul 12 00:23:56.997923 kernel: ACPI: SRAT not present Jul 12 00:23:56.997939 kernel: percpu: Embedded 30 pages/cpu s82968 r8192 d31720 u122880 Jul 12 00:23:56.997954 kernel: pcpu-alloc: s82968 r8192 d31720 u122880 alloc=30*4096 Jul 12 00:23:56.997969 kernel: pcpu-alloc: [0] 0 [0] 1 Jul 12 00:23:56.997984 kernel: Detected PIPT I-cache on CPU0 Jul 12 00:23:56.997999 kernel: CPU features: detected: GIC system register CPU interface Jul 12 00:23:56.998014 kernel: CPU features: detected: Spectre-v2 Jul 12 00:23:56.998029 kernel: CPU features: detected: Spectre-v3a Jul 12 00:23:56.998043 kernel: CPU features: detected: Spectre-BHB Jul 12 00:23:56.998058 kernel: CPU features: kernel page table isolation forced ON by KASLR Jul 12 00:23:56.998077 kernel: CPU features: detected: Kernel page table isolation (KPTI) Jul 12 00:23:56.998092 kernel: CPU features: detected: ARM erratum 1742098 Jul 12 00:23:56.998107 kernel: CPU features: detected: ARM errata 1165522, 1319367, or 1530923 Jul 12 00:23:56.998122 kernel: Built 1 zonelists, mobility grouping on. Total pages: 991872 Jul 12 00:23:56.998136 kernel: Policy zone: Normal Jul 12 00:23:56.998154 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=6cb548cec1e3020e9c3dcbc1d7670f4d8bdc2e3c8e062898ccaed7fc9d588f65 Jul 12 00:23:56.998170 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Jul 12 00:23:56.998186 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Jul 12 00:23:56.998201 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Jul 12 00:23:56.998216 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jul 12 00:23:56.998235 kernel: software IO TLB: mapped [mem 0x000000007c000000-0x0000000080000000] (64MB) Jul 12 00:23:56.998251 kernel: Memory: 3824460K/4030464K available (9792K kernel code, 2094K rwdata, 7588K rodata, 36416K init, 777K bss, 206004K reserved, 0K cma-reserved) Jul 12 00:23:56.998266 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Jul 12 00:23:56.998281 kernel: trace event string verifier disabled Jul 12 00:23:56.998296 kernel: rcu: Preemptible hierarchical RCU implementation. Jul 12 00:23:56.998312 kernel: rcu: RCU event tracing is enabled. Jul 12 00:23:56.998327 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Jul 12 00:23:56.998342 kernel: Trampoline variant of Tasks RCU enabled. Jul 12 00:23:56.998358 kernel: Tracing variant of Tasks RCU enabled. Jul 12 00:23:56.998373 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Jul 12 00:23:56.998388 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Jul 12 00:23:56.998402 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Jul 12 00:23:56.998421 kernel: GICv3: 96 SPIs implemented Jul 12 00:23:56.998436 kernel: GICv3: 0 Extended SPIs implemented Jul 12 00:23:56.998451 kernel: GICv3: Distributor has no Range Selector support Jul 12 00:23:56.998465 kernel: Root IRQ handler: gic_handle_irq Jul 12 00:23:56.998480 kernel: GICv3: 16 PPIs implemented Jul 12 00:23:56.998495 kernel: GICv3: CPU0: found redistributor 0 region 0:0x0000000010200000 Jul 12 00:23:56.998509 kernel: ACPI: SRAT not present Jul 12 00:23:56.998524 kernel: ITS [mem 0x10080000-0x1009ffff] Jul 12 00:23:56.998539 kernel: ITS@0x0000000010080000: allocated 8192 Devices @400090000 (indirect, esz 8, psz 64K, shr 1) Jul 12 00:23:56.998554 kernel: ITS@0x0000000010080000: allocated 8192 Interrupt Collections @4000a0000 (flat, esz 8, psz 64K, shr 1) Jul 12 00:23:56.998569 kernel: GICv3: using LPI property table @0x00000004000b0000 Jul 12 00:23:56.998588 kernel: ITS: Using hypervisor restricted LPI range [128] Jul 12 00:23:56.998603 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000004000d0000 Jul 12 00:23:56.998618 kernel: arch_timer: cp15 timer(s) running at 83.33MHz (virt). Jul 12 00:23:56.998633 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x13381ebeec, max_idle_ns: 440795203145 ns Jul 12 00:23:56.998648 kernel: sched_clock: 56 bits at 83MHz, resolution 12ns, wraps every 4398046511100ns Jul 12 00:23:56.998663 kernel: Console: colour dummy device 80x25 Jul 12 00:23:56.998678 kernel: printk: console [tty1] enabled Jul 12 00:23:56.998707 kernel: ACPI: Core revision 20210730 Jul 12 00:23:56.998742 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 166.66 BogoMIPS (lpj=83333) Jul 12 00:23:56.998761 kernel: pid_max: default: 32768 minimum: 301 Jul 12 00:23:56.998781 kernel: LSM: Security Framework initializing Jul 12 00:23:56.998797 kernel: SELinux: Initializing. Jul 12 00:23:56.998813 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Jul 12 00:23:56.998828 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Jul 12 00:23:56.998844 kernel: rcu: Hierarchical SRCU implementation. Jul 12 00:23:56.998859 kernel: Platform MSI: ITS@0x10080000 domain created Jul 12 00:23:56.998875 kernel: PCI/MSI: ITS@0x10080000 domain created Jul 12 00:23:56.998890 kernel: Remapping and enabling EFI services. Jul 12 00:23:56.998905 kernel: smp: Bringing up secondary CPUs ... Jul 12 00:23:56.998921 kernel: Detected PIPT I-cache on CPU1 Jul 12 00:23:56.998941 kernel: GICv3: CPU1: found redistributor 1 region 0:0x0000000010220000 Jul 12 00:23:56.998956 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000004000e0000 Jul 12 00:23:56.998972 kernel: CPU1: Booted secondary processor 0x0000000001 [0x410fd083] Jul 12 00:23:56.998987 kernel: smp: Brought up 1 node, 2 CPUs Jul 12 00:23:56.999002 kernel: SMP: Total of 2 processors activated. Jul 12 00:23:56.999018 kernel: CPU features: detected: 32-bit EL0 Support Jul 12 00:23:56.999033 kernel: CPU features: detected: 32-bit EL1 Support Jul 12 00:23:56.999048 kernel: CPU features: detected: CRC32 instructions Jul 12 00:23:56.999063 kernel: CPU: All CPU(s) started at EL1 Jul 12 00:23:56.999083 kernel: alternatives: patching kernel code Jul 12 00:23:56.999099 kernel: devtmpfs: initialized Jul 12 00:23:56.999125 kernel: KASLR disabled due to lack of seed Jul 12 00:23:56.999145 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Jul 12 00:23:56.999161 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Jul 12 00:23:56.999177 kernel: pinctrl core: initialized pinctrl subsystem Jul 12 00:23:56.999193 kernel: SMBIOS 3.0.0 present. Jul 12 00:23:56.999209 kernel: DMI: Amazon EC2 a1.large/, BIOS 1.0 11/1/2018 Jul 12 00:23:56.999225 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jul 12 00:23:56.999241 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Jul 12 00:23:56.999257 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Jul 12 00:23:56.999277 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Jul 12 00:23:56.999293 kernel: audit: initializing netlink subsys (disabled) Jul 12 00:23:56.999310 kernel: audit: type=2000 audit(0.293:1): state=initialized audit_enabled=0 res=1 Jul 12 00:23:56.999326 kernel: thermal_sys: Registered thermal governor 'step_wise' Jul 12 00:23:56.999341 kernel: cpuidle: using governor menu Jul 12 00:23:56.999361 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Jul 12 00:23:56.999377 kernel: ASID allocator initialised with 32768 entries Jul 12 00:23:56.999393 kernel: ACPI: bus type PCI registered Jul 12 00:23:56.999409 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jul 12 00:23:56.999425 kernel: Serial: AMBA PL011 UART driver Jul 12 00:23:56.999441 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Jul 12 00:23:56.999457 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Jul 12 00:23:56.999486 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Jul 12 00:23:56.999509 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Jul 12 00:23:56.999530 kernel: cryptd: max_cpu_qlen set to 1000 Jul 12 00:23:56.999547 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Jul 12 00:23:56.999563 kernel: ACPI: Added _OSI(Module Device) Jul 12 00:23:56.999579 kernel: ACPI: Added _OSI(Processor Device) Jul 12 00:23:56.999595 kernel: ACPI: Added _OSI(Processor Aggregator Device) Jul 12 00:23:56.999611 kernel: ACPI: Added _OSI(Linux-Dell-Video) Jul 12 00:23:56.999627 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Jul 12 00:23:56.999643 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Jul 12 00:23:56.999659 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Jul 12 00:23:56.999675 kernel: ACPI: Interpreter enabled Jul 12 00:23:56.999695 kernel: ACPI: Using GIC for interrupt routing Jul 12 00:23:56.999711 kernel: ACPI: MCFG table detected, 1 entries Jul 12 00:23:56.999748 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-0f]) Jul 12 00:23:57.000024 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Jul 12 00:23:57.000214 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Jul 12 00:23:57.000399 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Jul 12 00:23:57.000580 kernel: acpi PNP0A08:00: ECAM area [mem 0x20000000-0x20ffffff] reserved by PNP0C02:00 Jul 12 00:23:57.000791 kernel: acpi PNP0A08:00: ECAM at [mem 0x20000000-0x20ffffff] for [bus 00-0f] Jul 12 00:23:57.000816 kernel: ACPI: Remapped I/O 0x000000001fff0000 to [io 0x0000-0xffff window] Jul 12 00:23:57.000833 kernel: acpiphp: Slot [1] registered Jul 12 00:23:57.000849 kernel: acpiphp: Slot [2] registered Jul 12 00:23:57.000865 kernel: acpiphp: Slot [3] registered Jul 12 00:23:57.000881 kernel: acpiphp: Slot [4] registered Jul 12 00:23:57.000897 kernel: acpiphp: Slot [5] registered Jul 12 00:23:57.000913 kernel: acpiphp: Slot [6] registered Jul 12 00:23:57.000928 kernel: acpiphp: Slot [7] registered Jul 12 00:23:57.000967 kernel: acpiphp: Slot [8] registered Jul 12 00:23:57.000984 kernel: acpiphp: Slot [9] registered Jul 12 00:23:57.001000 kernel: acpiphp: Slot [10] registered Jul 12 00:23:57.001016 kernel: acpiphp: Slot [11] registered Jul 12 00:23:57.001032 kernel: acpiphp: Slot [12] registered Jul 12 00:23:57.001048 kernel: acpiphp: Slot [13] registered Jul 12 00:23:57.001064 kernel: acpiphp: Slot [14] registered Jul 12 00:23:57.001080 kernel: acpiphp: Slot [15] registered Jul 12 00:23:57.001096 kernel: acpiphp: Slot [16] registered Jul 12 00:23:57.001116 kernel: acpiphp: Slot [17] registered Jul 12 00:23:57.001132 kernel: acpiphp: Slot [18] registered Jul 12 00:23:57.001147 kernel: acpiphp: Slot [19] registered Jul 12 00:23:57.001163 kernel: acpiphp: Slot [20] registered Jul 12 00:23:57.001179 kernel: acpiphp: Slot [21] registered Jul 12 00:23:57.001195 kernel: acpiphp: Slot [22] registered Jul 12 00:23:57.001210 kernel: acpiphp: Slot [23] registered Jul 12 00:23:57.001226 kernel: acpiphp: Slot [24] registered Jul 12 00:23:57.001242 kernel: acpiphp: Slot [25] registered Jul 12 00:23:57.001258 kernel: acpiphp: Slot [26] registered Jul 12 00:23:57.001277 kernel: acpiphp: Slot [27] registered Jul 12 00:23:57.001293 kernel: acpiphp: Slot [28] registered Jul 12 00:23:57.001309 kernel: acpiphp: Slot [29] registered Jul 12 00:23:57.001324 kernel: acpiphp: Slot [30] registered Jul 12 00:23:57.001340 kernel: acpiphp: Slot [31] registered Jul 12 00:23:57.001356 kernel: PCI host bridge to bus 0000:00 Jul 12 00:23:57.001547 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xffffffff window] Jul 12 00:23:57.001730 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Jul 12 00:23:57.001912 kernel: pci_bus 0000:00: root bus resource [mem 0x400000000000-0x407fffffffff window] Jul 12 00:23:57.002088 kernel: pci_bus 0000:00: root bus resource [bus 00-0f] Jul 12 00:23:57.002311 kernel: pci 0000:00:00.0: [1d0f:0200] type 00 class 0x060000 Jul 12 00:23:57.002523 kernel: pci 0000:00:01.0: [1d0f:8250] type 00 class 0x070003 Jul 12 00:23:57.002733 kernel: pci 0000:00:01.0: reg 0x10: [mem 0x80118000-0x80118fff] Jul 12 00:23:57.002949 kernel: pci 0000:00:04.0: [1d0f:8061] type 00 class 0x010802 Jul 12 00:23:57.003141 kernel: pci 0000:00:04.0: reg 0x10: [mem 0x80114000-0x80117fff] Jul 12 00:23:57.003332 kernel: pci 0000:00:04.0: PME# supported from D0 D1 D2 D3hot D3cold Jul 12 00:23:57.003540 kernel: pci 0000:00:05.0: [1d0f:ec20] type 00 class 0x020000 Jul 12 00:23:57.010828 kernel: pci 0000:00:05.0: reg 0x10: [mem 0x80110000-0x80113fff] Jul 12 00:23:57.011087 kernel: pci 0000:00:05.0: reg 0x18: [mem 0x80000000-0x800fffff pref] Jul 12 00:23:57.011300 kernel: pci 0000:00:05.0: reg 0x20: [mem 0x80100000-0x8010ffff] Jul 12 00:23:57.024140 kernel: pci 0000:00:05.0: PME# supported from D0 D1 D2 D3hot D3cold Jul 12 00:23:57.026968 kernel: pci 0000:00:05.0: BAR 2: assigned [mem 0x80000000-0x800fffff pref] Jul 12 00:23:57.027176 kernel: pci 0000:00:05.0: BAR 4: assigned [mem 0x80100000-0x8010ffff] Jul 12 00:23:57.027371 kernel: pci 0000:00:04.0: BAR 0: assigned [mem 0x80110000-0x80113fff] Jul 12 00:23:57.027560 kernel: pci 0000:00:05.0: BAR 0: assigned [mem 0x80114000-0x80117fff] Jul 12 00:23:57.027786 kernel: pci 0000:00:01.0: BAR 0: assigned [mem 0x80118000-0x80118fff] Jul 12 00:23:57.029504 kernel: pci_bus 0000:00: resource 4 [mem 0x80000000-0xffffffff window] Jul 12 00:23:57.029704 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Jul 12 00:23:57.029917 kernel: pci_bus 0000:00: resource 6 [mem 0x400000000000-0x407fffffffff window] Jul 12 00:23:57.029941 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Jul 12 00:23:57.029959 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Jul 12 00:23:57.029976 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Jul 12 00:23:57.029992 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Jul 12 00:23:57.030008 kernel: iommu: Default domain type: Translated Jul 12 00:23:57.030025 kernel: iommu: DMA domain TLB invalidation policy: strict mode Jul 12 00:23:57.030041 kernel: vgaarb: loaded Jul 12 00:23:57.030057 kernel: pps_core: LinuxPPS API ver. 1 registered Jul 12 00:23:57.030078 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Jul 12 00:23:57.030095 kernel: PTP clock support registered Jul 12 00:23:57.030111 kernel: Registered efivars operations Jul 12 00:23:57.030127 kernel: clocksource: Switched to clocksource arch_sys_counter Jul 12 00:23:57.030143 kernel: VFS: Disk quotas dquot_6.6.0 Jul 12 00:23:57.030159 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jul 12 00:23:57.030175 kernel: pnp: PnP ACPI init Jul 12 00:23:57.030364 kernel: system 00:00: [mem 0x20000000-0x2fffffff] could not be reserved Jul 12 00:23:57.030393 kernel: pnp: PnP ACPI: found 1 devices Jul 12 00:23:57.030410 kernel: NET: Registered PF_INET protocol family Jul 12 00:23:57.030426 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Jul 12 00:23:57.030443 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Jul 12 00:23:57.030459 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jul 12 00:23:57.030475 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Jul 12 00:23:57.030492 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Jul 12 00:23:57.030508 kernel: TCP: Hash tables configured (established 32768 bind 32768) Jul 12 00:23:57.030524 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Jul 12 00:23:57.030544 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Jul 12 00:23:57.030560 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jul 12 00:23:57.030576 kernel: PCI: CLS 0 bytes, default 64 Jul 12 00:23:57.030592 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 3 counters available Jul 12 00:23:57.030608 kernel: kvm [1]: HYP mode not available Jul 12 00:23:57.030624 kernel: Initialise system trusted keyrings Jul 12 00:23:57.030641 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Jul 12 00:23:57.030657 kernel: Key type asymmetric registered Jul 12 00:23:57.030673 kernel: Asymmetric key parser 'x509' registered Jul 12 00:23:57.030693 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Jul 12 00:23:57.030709 kernel: io scheduler mq-deadline registered Jul 12 00:23:57.030745 kernel: io scheduler kyber registered Jul 12 00:23:57.030764 kernel: io scheduler bfq registered Jul 12 00:23:57.030981 kernel: pl061_gpio ARMH0061:00: PL061 GPIO chip registered Jul 12 00:23:57.031006 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Jul 12 00:23:57.031023 kernel: ACPI: button: Power Button [PWRB] Jul 12 00:23:57.031040 kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0E:00/input/input1 Jul 12 00:23:57.031056 kernel: ACPI: button: Sleep Button [SLPB] Jul 12 00:23:57.031078 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Jul 12 00:23:57.031095 kernel: ACPI: \_SB_.PCI0.GSI2: Enabled at IRQ 37 Jul 12 00:23:57.031286 kernel: serial 0000:00:01.0: enabling device (0010 -> 0012) Jul 12 00:23:57.031310 kernel: printk: console [ttyS0] disabled Jul 12 00:23:57.031327 kernel: 0000:00:01.0: ttyS0 at MMIO 0x80118000 (irq = 14, base_baud = 115200) is a 16550A Jul 12 00:23:57.031343 kernel: printk: console [ttyS0] enabled Jul 12 00:23:57.031359 kernel: printk: bootconsole [uart0] disabled Jul 12 00:23:57.031375 kernel: thunder_xcv, ver 1.0 Jul 12 00:23:57.031391 kernel: thunder_bgx, ver 1.0 Jul 12 00:23:57.031412 kernel: nicpf, ver 1.0 Jul 12 00:23:57.031428 kernel: nicvf, ver 1.0 Jul 12 00:23:57.031619 kernel: rtc-efi rtc-efi.0: registered as rtc0 Jul 12 00:23:57.031825 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-07-12T00:23:56 UTC (1752279836) Jul 12 00:23:57.031850 kernel: hid: raw HID events driver (C) Jiri Kosina Jul 12 00:23:57.031867 kernel: NET: Registered PF_INET6 protocol family Jul 12 00:23:57.031883 kernel: Segment Routing with IPv6 Jul 12 00:23:57.031899 kernel: In-situ OAM (IOAM) with IPv6 Jul 12 00:23:57.031920 kernel: NET: Registered PF_PACKET protocol family Jul 12 00:23:57.031936 kernel: Key type dns_resolver registered Jul 12 00:23:57.031952 kernel: registered taskstats version 1 Jul 12 00:23:57.031968 kernel: Loading compiled-in X.509 certificates Jul 12 00:23:57.031984 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.15.186-flatcar: de2ee1d04443f96c763927c453375bbe23b5752a' Jul 12 00:23:57.032000 kernel: Key type .fscrypt registered Jul 12 00:23:57.032015 kernel: Key type fscrypt-provisioning registered Jul 12 00:23:57.032031 kernel: ima: No TPM chip found, activating TPM-bypass! Jul 12 00:23:57.032047 kernel: ima: Allocated hash algorithm: sha1 Jul 12 00:23:57.032068 kernel: ima: No architecture policies found Jul 12 00:23:57.032084 kernel: clk: Disabling unused clocks Jul 12 00:23:57.032099 kernel: Freeing unused kernel memory: 36416K Jul 12 00:23:57.032115 kernel: Run /init as init process Jul 12 00:23:57.032131 kernel: with arguments: Jul 12 00:23:57.032146 kernel: /init Jul 12 00:23:57.032162 kernel: with environment: Jul 12 00:23:57.032177 kernel: HOME=/ Jul 12 00:23:57.032193 kernel: TERM=linux Jul 12 00:23:57.032212 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Jul 12 00:23:57.032234 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) Jul 12 00:23:57.032255 systemd[1]: Detected virtualization amazon. Jul 12 00:23:57.032272 systemd[1]: Detected architecture arm64. Jul 12 00:23:57.032290 systemd[1]: Running in initrd. Jul 12 00:23:57.032307 systemd[1]: No hostname configured, using default hostname. Jul 12 00:23:57.032324 systemd[1]: Hostname set to . Jul 12 00:23:57.032346 systemd[1]: Initializing machine ID from VM UUID. Jul 12 00:23:57.032364 systemd[1]: Queued start job for default target initrd.target. Jul 12 00:23:57.032381 systemd[1]: Started systemd-ask-password-console.path. Jul 12 00:23:57.032398 systemd[1]: Reached target cryptsetup.target. Jul 12 00:23:57.032415 systemd[1]: Reached target paths.target. Jul 12 00:23:57.032432 systemd[1]: Reached target slices.target. Jul 12 00:23:57.032449 systemd[1]: Reached target swap.target. Jul 12 00:23:57.032466 systemd[1]: Reached target timers.target. Jul 12 00:23:57.032488 systemd[1]: Listening on iscsid.socket. Jul 12 00:23:57.032506 systemd[1]: Listening on iscsiuio.socket. Jul 12 00:23:57.032523 systemd[1]: Listening on systemd-journald-audit.socket. Jul 12 00:23:57.032540 systemd[1]: Listening on systemd-journald-dev-log.socket. Jul 12 00:23:57.032557 systemd[1]: Listening on systemd-journald.socket. Jul 12 00:23:57.032575 systemd[1]: Listening on systemd-networkd.socket. Jul 12 00:23:57.032592 systemd[1]: Listening on systemd-udevd-control.socket. Jul 12 00:23:57.032610 systemd[1]: Listening on systemd-udevd-kernel.socket. Jul 12 00:23:57.032631 systemd[1]: Reached target sockets.target. Jul 12 00:23:57.032649 systemd[1]: Starting kmod-static-nodes.service... Jul 12 00:23:57.032666 systemd[1]: Finished network-cleanup.service. Jul 12 00:23:57.032683 systemd[1]: Starting systemd-fsck-usr.service... Jul 12 00:23:57.032701 systemd[1]: Starting systemd-journald.service... Jul 12 00:23:57.032745 systemd[1]: Starting systemd-modules-load.service... Jul 12 00:23:57.032770 systemd[1]: Starting systemd-resolved.service... Jul 12 00:23:57.032788 systemd[1]: Starting systemd-vconsole-setup.service... Jul 12 00:23:57.032805 systemd[1]: Finished kmod-static-nodes.service. Jul 12 00:23:57.032829 kernel: audit: type=1130 audit(1752279836.983: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' Jul 12 00:23:57.032847 systemd[1]: Finished systemd-fsck-usr.service. Jul 12 00:23:57.032865 kernel: audit: type=1130 audit(1752279836.997: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' Jul 12 00:23:57.032882 systemd[1]: Finished systemd-vconsole-setup.service. Jul 12 00:23:57.032900 kernel: audit: type=1130 audit(1752279837.009: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' Jul 12 00:23:57.032917 systemd[1]: Starting dracut-cmdline-ask.service... Jul 12 00:23:57.032953 systemd-journald[309]: Journal started Jul 12 00:23:57.033045 systemd-journald[309]: Runtime Journal (/run/log/journal/ec2d07447f3aa9ac1a8ad7e3c5f524cd) is 8.0M, max 75.4M, 67.4M free. Jul 12 00:23:56.983000 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' Jul 12 00:23:56.997000 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' Jul 12 00:23:57.009000 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' Jul 12 00:23:56.980199 systemd-modules-load[310]: Inserted module 'overlay' Jul 12 00:23:57.045146 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... Jul 12 00:23:57.045191 systemd[1]: Started systemd-journald.service. Jul 12 00:23:57.064711 kernel: audit: type=1130 audit(1752279837.055: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' Jul 12 00:23:57.055000 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' Jul 12 00:23:57.076915 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. Jul 12 00:23:57.075000 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' Jul 12 00:23:57.099872 kernel: audit: type=1130 audit(1752279837.075:6): 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' Jul 12 00:23:57.099936 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Jul 12 00:23:57.100685 systemd-resolved[311]: Positive Trust Anchors: Jul 12 00:23:57.102816 systemd-resolved[311]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 12 00:23:57.103459 systemd[1]: Finished dracut-cmdline-ask.service. Jul 12 00:23:57.121938 kernel: Bridge firewalling registered Jul 12 00:23:57.121975 kernel: audit: type=1130 audit(1752279837.109:7): 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' Jul 12 00:23:57.109000 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' Jul 12 00:23:57.106014 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 Jul 12 00:23:57.121273 systemd[1]: Starting dracut-cmdline.service... Jul 12 00:23:57.128854 systemd-modules-load[310]: Inserted module 'br_netfilter' Jul 12 00:23:57.168756 kernel: SCSI subsystem initialized Jul 12 00:23:57.172829 dracut-cmdline[326]: dracut-dracut-053 Jul 12 00:23:57.189656 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Jul 12 00:23:57.189739 kernel: device-mapper: uevent: version 1.0.3 Jul 12 00:23:57.189872 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=6cb548cec1e3020e9c3dcbc1d7670f4d8bdc2e3c8e062898ccaed7fc9d588f65 Jul 12 00:23:57.207475 kernel: device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com Jul 12 00:23:57.206102 systemd-modules-load[310]: Inserted module 'dm_multipath' Jul 12 00:23:57.208000 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' Jul 12 00:23:57.207416 systemd[1]: Finished systemd-modules-load.service. Jul 12 00:23:57.210626 systemd[1]: Starting systemd-sysctl.service... Jul 12 00:23:57.230801 kernel: audit: type=1130 audit(1752279837.208: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' Jul 12 00:23:57.247304 systemd[1]: Finished systemd-sysctl.service. Jul 12 00:23:57.261112 kernel: audit: type=1130 audit(1752279837.248: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' Jul 12 00:23:57.248000 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' Jul 12 00:23:57.345759 kernel: Loading iSCSI transport class v2.0-870. Jul 12 00:23:57.365757 kernel: iscsi: registered transport (tcp) Jul 12 00:23:57.393980 kernel: iscsi: registered transport (qla4xxx) Jul 12 00:23:57.394061 kernel: QLogic iSCSI HBA Driver Jul 12 00:23:57.567762 kernel: random: crng init done Jul 12 00:23:57.568099 systemd-resolved[311]: Defaulting to hostname 'linux'. Jul 12 00:23:57.572026 systemd[1]: Started systemd-resolved.service. Jul 12 00:23:57.572000 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' Jul 12 00:23:57.574004 systemd[1]: Reached target nss-lookup.target. Jul 12 00:23:57.588999 kernel: audit: type=1130 audit(1752279837.572: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' Jul 12 00:23:57.597235 systemd[1]: Finished dracut-cmdline.service. Jul 12 00:23:57.597000 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' Jul 12 00:23:57.600645 systemd[1]: Starting dracut-pre-udev.service... Jul 12 00:23:57.665792 kernel: raid6: neonx8 gen() 6372 MB/s Jul 12 00:23:57.683755 kernel: raid6: neonx8 xor() 4702 MB/s Jul 12 00:23:57.701753 kernel: raid6: neonx4 gen() 6543 MB/s Jul 12 00:23:57.719755 kernel: raid6: neonx4 xor() 4875 MB/s Jul 12 00:23:57.737755 kernel: raid6: neonx2 gen() 5784 MB/s Jul 12 00:23:57.755756 kernel: raid6: neonx2 xor() 4494 MB/s Jul 12 00:23:57.773755 kernel: raid6: neonx1 gen() 4473 MB/s Jul 12 00:23:57.791753 kernel: raid6: neonx1 xor() 3662 MB/s Jul 12 00:23:57.809755 kernel: raid6: int64x8 gen() 3432 MB/s Jul 12 00:23:57.827754 kernel: raid6: int64x8 xor() 2083 MB/s Jul 12 00:23:57.845757 kernel: raid6: int64x4 gen() 3820 MB/s Jul 12 00:23:57.863755 kernel: raid6: int64x4 xor() 2187 MB/s Jul 12 00:23:57.881755 kernel: raid6: int64x2 gen() 3602 MB/s Jul 12 00:23:57.899754 kernel: raid6: int64x2 xor() 1948 MB/s Jul 12 00:23:57.917757 kernel: raid6: int64x1 gen() 2755 MB/s Jul 12 00:23:57.937310 kernel: raid6: int64x1 xor() 1445 MB/s Jul 12 00:23:57.937342 kernel: raid6: using algorithm neonx4 gen() 6543 MB/s Jul 12 00:23:57.937366 kernel: raid6: .... xor() 4875 MB/s, rmw enabled Jul 12 00:23:57.939165 kernel: raid6: using neon recovery algorithm Jul 12 00:23:57.957767 kernel: xor: measuring software checksum speed Jul 12 00:23:57.959755 kernel: 8regs : 8807 MB/sec Jul 12 00:23:57.961757 kernel: 32regs : 10392 MB/sec Jul 12 00:23:57.965507 kernel: arm64_neon : 8575 MB/sec Jul 12 00:23:57.965540 kernel: xor: using function: 32regs (10392 MB/sec) Jul 12 00:23:58.062771 kernel: Btrfs loaded, crc32c=crc32c-generic, zoned=no, fsverity=no Jul 12 00:23:58.079919 systemd[1]: Finished dracut-pre-udev.service. Jul 12 00:23:58.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' Jul 12 00:23:58.082000 audit: BPF prog-id=7 op=LOAD Jul 12 00:23:58.082000 audit: BPF prog-id=8 op=LOAD Jul 12 00:23:58.085129 systemd[1]: Starting systemd-udevd.service... Jul 12 00:23:58.116375 systemd-udevd[509]: Using default interface naming scheme 'v252'. Jul 12 00:23:58.127000 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' Jul 12 00:23:58.125480 systemd[1]: Started systemd-udevd.service. Jul 12 00:23:58.138037 systemd[1]: Starting dracut-pre-trigger.service... Jul 12 00:23:58.162666 dracut-pre-trigger[521]: rd.md=0: removing MD RAID activation Jul 12 00:23:58.221014 systemd[1]: Finished dracut-pre-trigger.service. Jul 12 00:23:58.219000 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' Jul 12 00:23:58.222498 systemd[1]: Starting systemd-udev-trigger.service... Jul 12 00:23:58.320760 systemd[1]: Finished systemd-udev-trigger.service. Jul 12 00:23:58.319000 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' Jul 12 00:23:58.454027 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Jul 12 00:23:58.454094 kernel: ena 0000:00:05.0: enabling device (0010 -> 0012) Jul 12 00:23:58.472757 kernel: ena 0000:00:05.0: ENA device version: 0.10 Jul 12 00:23:58.472999 kernel: ena 0000:00:05.0: ENA controller version: 0.0.1 implementation version 1 Jul 12 00:23:58.473209 kernel: ACPI: \_SB_.PCI0.GSI0: Enabled at IRQ 35 Jul 12 00:23:58.473234 kernel: nvme nvme0: pci function 0000:00:04.0 Jul 12 00:23:58.473465 kernel: ena 0000:00:05.0: Elastic Network Adapter (ENA) found at mem 80114000, mac addr 06:df:a9:0d:94:a1 Jul 12 00:23:58.478783 kernel: nvme nvme0: 2/0/0 default/read/poll queues Jul 12 00:23:58.488801 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Jul 12 00:23:58.488846 kernel: GPT:9289727 != 16777215 Jul 12 00:23:58.488870 kernel: GPT:Alternate GPT header not at the end of the disk. Jul 12 00:23:58.490999 kernel: GPT:9289727 != 16777215 Jul 12 00:23:58.492267 kernel: GPT: Use GNU Parted to correct GPT errors. Jul 12 00:23:58.494175 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Jul 12 00:23:58.498537 (udev-worker)[558]: Network interface NamePolicy= disabled on kernel command line. Jul 12 00:23:58.577764 kernel: BTRFS: device label OEM devid 1 transid 9 /dev/nvme0n1p6 scanned by (udev-worker) (573) Jul 12 00:23:58.619165 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device. Jul 12 00:23:58.693804 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Jul 12 00:23:58.704835 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device. Jul 12 00:23:58.704999 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device. Jul 12 00:23:58.716156 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device. Jul 12 00:23:58.718405 systemd[1]: Starting disk-uuid.service... Jul 12 00:23:58.738758 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Jul 12 00:23:58.742121 disk-uuid[671]: Primary Header is updated. Jul 12 00:23:58.742121 disk-uuid[671]: Secondary Entries is updated. Jul 12 00:23:58.742121 disk-uuid[671]: Secondary Header is updated. Jul 12 00:23:58.773772 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Jul 12 00:23:59.774767 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Jul 12 00:23:59.774968 disk-uuid[672]: The operation has completed successfully. Jul 12 00:23:59.940848 systemd[1]: disk-uuid.service: Deactivated successfully. Jul 12 00:23:59.941000 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' Jul 12 00:23:59.942000 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' Jul 12 00:23:59.941072 systemd[1]: Finished disk-uuid.service. Jul 12 00:23:59.974795 systemd[1]: Starting verity-setup.service... Jul 12 00:24:00.012778 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Jul 12 00:24:00.113499 systemd[1]: Found device dev-mapper-usr.device. Jul 12 00:24:00.118343 systemd[1]: Finished verity-setup.service. Jul 12 00:24:00.119000 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' Jul 12 00:24:00.123092 systemd[1]: Mounting sysusr-usr.mount... Jul 12 00:24:00.226789 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: norecovery. Quota mode: none. Jul 12 00:24:00.227614 systemd[1]: Mounted sysusr-usr.mount. Jul 12 00:24:00.228009 systemd[1]: afterburn-network-kargs.service was skipped because no trigger condition checks were met. Jul 12 00:24:00.229284 systemd[1]: Starting ignition-setup.service... Jul 12 00:24:00.245178 systemd[1]: Starting parse-ip-for-networkd.service... Jul 12 00:24:00.281911 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Jul 12 00:24:00.281987 kernel: BTRFS info (device nvme0n1p6): using free space tree Jul 12 00:24:00.282012 kernel: BTRFS info (device nvme0n1p6): has skinny extents Jul 12 00:24:00.323017 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Jul 12 00:24:00.341599 systemd[1]: mnt-oem.mount: Deactivated successfully. Jul 12 00:24:00.370251 systemd[1]: Finished ignition-setup.service. Jul 12 00:24:00.370000 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' Jul 12 00:24:00.375186 systemd[1]: Starting ignition-fetch-offline.service... Jul 12 00:24:00.402584 systemd[1]: Finished parse-ip-for-networkd.service. Jul 12 00:24:00.404000 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' Jul 12 00:24:00.406000 audit: BPF prog-id=9 op=LOAD Jul 12 00:24:00.408687 systemd[1]: Starting systemd-networkd.service... Jul 12 00:24:00.456834 systemd-networkd[1184]: lo: Link UP Jul 12 00:24:00.456857 systemd-networkd[1184]: lo: Gained carrier Jul 12 00:24:00.458430 systemd-networkd[1184]: Enumeration completed Jul 12 00:24:00.462000 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' Jul 12 00:24:00.459621 systemd-networkd[1184]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 12 00:24:00.461472 systemd[1]: Started systemd-networkd.service. Jul 12 00:24:00.464135 systemd[1]: Reached target network.target. Jul 12 00:24:00.466186 systemd-networkd[1184]: eth0: Link UP Jul 12 00:24:00.466196 systemd-networkd[1184]: eth0: Gained carrier Jul 12 00:24:00.466508 systemd[1]: Starting iscsiuio.service... Jul 12 00:24:00.495994 systemd[1]: Started iscsiuio.service. Jul 12 00:24:00.499131 systemd-networkd[1184]: eth0: DHCPv4 address 172.31.18.187/20, gateway 172.31.16.1 acquired from 172.31.16.1 Jul 12 00:24:00.498000 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' Jul 12 00:24:00.501846 systemd[1]: Starting iscsid.service... Jul 12 00:24:00.511902 iscsid[1189]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Jul 12 00:24:00.511902 iscsid[1189]: 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]. Jul 12 00:24:00.511902 iscsid[1189]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Jul 12 00:24:00.511902 iscsid[1189]: If using hardware iscsi like qla4xxx this message can be ignored. Jul 12 00:24:00.534526 iscsid[1189]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Jul 12 00:24:00.534526 iscsid[1189]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Jul 12 00:24:00.537525 systemd[1]: Started iscsid.service. Jul 12 00:24:00.541000 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' Jul 12 00:24:00.545520 systemd[1]: Starting dracut-initqueue.service... Jul 12 00:24:00.569485 systemd[1]: Finished dracut-initqueue.service. Jul 12 00:24:00.571000 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' Jul 12 00:24:00.573267 systemd[1]: Reached target remote-fs-pre.target. Jul 12 00:24:00.576714 systemd[1]: Reached target remote-cryptsetup.target. Jul 12 00:24:00.580355 systemd[1]: Reached target remote-fs.target. Jul 12 00:24:00.585657 systemd[1]: Starting dracut-pre-mount.service... Jul 12 00:24:00.604313 systemd[1]: Finished dracut-pre-mount.service. Jul 12 00:24:00.607000 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' Jul 12 00:24:00.860563 ignition[1169]: Ignition 2.14.0 Jul 12 00:24:00.862423 ignition[1169]: Stage: fetch-offline Jul 12 00:24:00.864424 ignition[1169]: reading system config file "/usr/lib/ignition/base.d/base.ign" Jul 12 00:24:00.866980 ignition[1169]: parsing config with SHA512: 6629d8e825d60c9c9d4629d8547ef9a0b839d6b01b7f61a481a1f23308c924b8b0bbf10cae7f7fe3bcaf88b23d1a81baa7771c3670728d4d2a1e665216a1de7b Jul 12 00:24:00.892185 ignition[1169]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Jul 12 00:24:00.892959 ignition[1169]: Ignition finished successfully Jul 12 00:24:00.897515 systemd[1]: Finished ignition-fetch-offline.service. Jul 12 00:24:00.911370 kernel: kauditd_printk_skb: 18 callbacks suppressed Jul 12 00:24:00.911441 kernel: audit: type=1130 audit(1752279840.899: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' Jul 12 00:24:00.899000 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' Jul 12 00:24:00.902965 systemd[1]: Starting ignition-fetch.service... Jul 12 00:24:00.922535 ignition[1208]: Ignition 2.14.0 Jul 12 00:24:00.922563 ignition[1208]: Stage: fetch Jul 12 00:24:00.922897 ignition[1208]: reading system config file "/usr/lib/ignition/base.d/base.ign" Jul 12 00:24:00.922956 ignition[1208]: parsing config with SHA512: 6629d8e825d60c9c9d4629d8547ef9a0b839d6b01b7f61a481a1f23308c924b8b0bbf10cae7f7fe3bcaf88b23d1a81baa7771c3670728d4d2a1e665216a1de7b Jul 12 00:24:00.938616 ignition[1208]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Jul 12 00:24:00.941019 ignition[1208]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Jul 12 00:24:00.950672 ignition[1208]: INFO : PUT result: OK Jul 12 00:24:00.954131 ignition[1208]: DEBUG : parsed url from cmdline: "" Jul 12 00:24:00.956150 ignition[1208]: INFO : no config URL provided Jul 12 00:24:00.956150 ignition[1208]: INFO : reading system config file "/usr/lib/ignition/user.ign" Jul 12 00:24:00.956150 ignition[1208]: INFO : no config at "/usr/lib/ignition/user.ign" Jul 12 00:24:00.956150 ignition[1208]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Jul 12 00:24:00.966271 ignition[1208]: INFO : PUT result: OK Jul 12 00:24:00.967954 ignition[1208]: INFO : GET http://169.254.169.254/2019-10-01/user-data: attempt #1 Jul 12 00:24:00.970959 ignition[1208]: INFO : GET result: OK Jul 12 00:24:00.972565 ignition[1208]: DEBUG : parsing config with SHA512: 87089d68afbec94b9dd21b0cb55ab4c9b65b9c2175077ac7845f27c89ab7e61269d116fb2d947184a6ff89833744486cc63c959f2fae2950ee35e8aa84b4793a Jul 12 00:24:00.986704 unknown[1208]: fetched base config from "system" Jul 12 00:24:00.986764 unknown[1208]: fetched base config from "system" Jul 12 00:24:00.986780 unknown[1208]: fetched user config from "aws" Jul 12 00:24:00.992589 ignition[1208]: fetch: fetch complete Jul 12 00:24:00.992616 ignition[1208]: fetch: fetch passed Jul 12 00:24:00.992751 ignition[1208]: Ignition finished successfully Jul 12 00:24:00.999856 systemd[1]: Finished ignition-fetch.service. Jul 12 00:24:01.017669 kernel: audit: type=1130 audit(1752279841.000: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' Jul 12 00:24:01.000000 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' Jul 12 00:24:01.003304 systemd[1]: Starting ignition-kargs.service... Jul 12 00:24:01.030578 ignition[1214]: Ignition 2.14.0 Jul 12 00:24:01.030606 ignition[1214]: Stage: kargs Jul 12 00:24:01.030922 ignition[1214]: reading system config file "/usr/lib/ignition/base.d/base.ign" Jul 12 00:24:01.030975 ignition[1214]: parsing config with SHA512: 6629d8e825d60c9c9d4629d8547ef9a0b839d6b01b7f61a481a1f23308c924b8b0bbf10cae7f7fe3bcaf88b23d1a81baa7771c3670728d4d2a1e665216a1de7b Jul 12 00:24:01.046825 ignition[1214]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Jul 12 00:24:01.049300 ignition[1214]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Jul 12 00:24:01.057378 ignition[1214]: INFO : PUT result: OK Jul 12 00:24:01.064182 ignition[1214]: kargs: kargs passed Jul 12 00:24:01.064283 ignition[1214]: Ignition finished successfully Jul 12 00:24:01.068747 systemd[1]: Finished ignition-kargs.service. Jul 12 00:24:01.068000 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' Jul 12 00:24:01.082093 kernel: audit: type=1130 audit(1752279841.068: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' Jul 12 00:24:01.079886 systemd[1]: Starting ignition-disks.service... Jul 12 00:24:01.095891 ignition[1220]: Ignition 2.14.0 Jul 12 00:24:01.097682 ignition[1220]: Stage: disks Jul 12 00:24:01.099257 ignition[1220]: reading system config file "/usr/lib/ignition/base.d/base.ign" Jul 12 00:24:01.101734 ignition[1220]: parsing config with SHA512: 6629d8e825d60c9c9d4629d8547ef9a0b839d6b01b7f61a481a1f23308c924b8b0bbf10cae7f7fe3bcaf88b23d1a81baa7771c3670728d4d2a1e665216a1de7b Jul 12 00:24:01.112250 ignition[1220]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Jul 12 00:24:01.117139 ignition[1220]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Jul 12 00:24:01.119797 ignition[1220]: INFO : PUT result: OK Jul 12 00:24:01.124248 ignition[1220]: disks: disks passed Jul 12 00:24:01.124532 ignition[1220]: Ignition finished successfully Jul 12 00:24:01.130351 systemd[1]: Finished ignition-disks.service. Jul 12 00:24:01.133000 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' Jul 12 00:24:01.135234 systemd[1]: Reached target initrd-root-device.target. Jul 12 00:24:01.144107 kernel: audit: type=1130 audit(1752279841.133: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' Jul 12 00:24:01.146043 systemd[1]: Reached target local-fs-pre.target. Jul 12 00:24:01.149554 systemd[1]: Reached target local-fs.target. Jul 12 00:24:01.152817 systemd[1]: Reached target sysinit.target. Jul 12 00:24:01.156058 systemd[1]: Reached target basic.target. Jul 12 00:24:01.160694 systemd[1]: Starting systemd-fsck-root.service... Jul 12 00:24:01.204468 systemd-fsck[1228]: ROOT: clean, 619/553520 files, 56022/553472 blocks Jul 12 00:24:01.211085 systemd[1]: Finished systemd-fsck-root.service. Jul 12 00:24:01.209000 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' Jul 12 00:24:01.212562 systemd[1]: Mounting sysroot.mount... Jul 12 00:24:01.228995 kernel: audit: type=1130 audit(1752279841.209: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' Jul 12 00:24:01.240764 kernel: EXT4-fs (nvme0n1p9): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none. Jul 12 00:24:01.242102 systemd[1]: Mounted sysroot.mount. Jul 12 00:24:01.245361 systemd[1]: Reached target initrd-root-fs.target. Jul 12 00:24:01.258019 systemd[1]: Mounting sysroot-usr.mount... Jul 12 00:24:01.265931 systemd[1]: flatcar-metadata-hostname.service was skipped because no trigger condition checks were met. Jul 12 00:24:01.266013 systemd[1]: ignition-remount-sysroot.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Jul 12 00:24:01.266070 systemd[1]: Reached target ignition-diskful.target. Jul 12 00:24:01.279673 systemd[1]: Mounted sysroot-usr.mount. Jul 12 00:24:01.289630 systemd[1]: Mounting sysroot-usr-share-oem.mount... Jul 12 00:24:01.298585 systemd[1]: Starting initrd-setup-root.service... Jul 12 00:24:01.317353 initrd-setup-root[1250]: cut: /sysroot/etc/passwd: No such file or directory Jul 12 00:24:01.323165 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/nvme0n1p6 scanned by mount (1245) Jul 12 00:24:01.332579 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Jul 12 00:24:01.332647 kernel: BTRFS info (device nvme0n1p6): using free space tree Jul 12 00:24:01.335308 kernel: BTRFS info (device nvme0n1p6): has skinny extents Jul 12 00:24:01.335856 initrd-setup-root[1258]: cut: /sysroot/etc/group: No such file or directory Jul 12 00:24:01.344304 initrd-setup-root[1282]: cut: /sysroot/etc/shadow: No such file or directory Jul 12 00:24:01.354429 initrd-setup-root[1290]: cut: /sysroot/etc/gshadow: No such file or directory Jul 12 00:24:01.362748 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Jul 12 00:24:01.373594 systemd[1]: Mounted sysroot-usr-share-oem.mount. Jul 12 00:24:01.462202 systemd[1]: Finished initrd-setup-root.service. Jul 12 00:24:01.465000 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' Jul 12 00:24:01.467810 systemd[1]: Starting ignition-mount.service... Jul 12 00:24:01.489533 kernel: audit: type=1130 audit(1752279841.465: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' Jul 12 00:24:01.482365 systemd[1]: Starting sysroot-boot.service... Jul 12 00:24:01.494156 systemd[1]: sysusr-usr-share-oem.mount: Deactivated successfully. Jul 12 00:24:01.494334 systemd[1]: sysroot-usr-share-oem.mount: Deactivated successfully. Jul 12 00:24:01.515472 ignition[1310]: INFO : Ignition 2.14.0 Jul 12 00:24:01.515472 ignition[1310]: INFO : Stage: mount Jul 12 00:24:01.518981 ignition[1310]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign" Jul 12 00:24:01.518981 ignition[1310]: DEBUG : parsing config with SHA512: 6629d8e825d60c9c9d4629d8547ef9a0b839d6b01b7f61a481a1f23308c924b8b0bbf10cae7f7fe3bcaf88b23d1a81baa7771c3670728d4d2a1e665216a1de7b Jul 12 00:24:01.539931 ignition[1310]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Jul 12 00:24:01.545287 systemd[1]: Finished sysroot-boot.service. Jul 12 00:24:01.547000 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' Jul 12 00:24:01.549142 ignition[1310]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Jul 12 00:24:01.558761 kernel: audit: type=1130 audit(1752279841.547: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' Jul 12 00:24:01.559063 ignition[1310]: INFO : PUT result: OK Jul 12 00:24:01.563744 ignition[1310]: INFO : mount: mount passed Jul 12 00:24:01.566923 ignition[1310]: INFO : Ignition finished successfully Jul 12 00:24:01.566762 systemd[1]: Finished ignition-mount.service. Jul 12 00:24:01.569000 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' Jul 12 00:24:01.571949 systemd[1]: Starting ignition-files.service... Jul 12 00:24:01.582797 kernel: audit: type=1130 audit(1752279841.569: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' Jul 12 00:24:01.590640 systemd[1]: Mounting sysroot-usr-share-oem.mount... Jul 12 00:24:01.614908 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/nvme0n1p6 scanned by mount (1320) Jul 12 00:24:01.620595 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Jul 12 00:24:01.620636 kernel: BTRFS info (device nvme0n1p6): using free space tree Jul 12 00:24:01.622828 kernel: BTRFS info (device nvme0n1p6): has skinny extents Jul 12 00:24:01.637763 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Jul 12 00:24:01.642694 systemd[1]: Mounted sysroot-usr-share-oem.mount. Jul 12 00:24:01.661964 ignition[1339]: INFO : Ignition 2.14.0 Jul 12 00:24:01.661964 ignition[1339]: INFO : Stage: files Jul 12 00:24:01.665433 ignition[1339]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign" Jul 12 00:24:01.665433 ignition[1339]: DEBUG : parsing config with SHA512: 6629d8e825d60c9c9d4629d8547ef9a0b839d6b01b7f61a481a1f23308c924b8b0bbf10cae7f7fe3bcaf88b23d1a81baa7771c3670728d4d2a1e665216a1de7b Jul 12 00:24:01.683191 ignition[1339]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Jul 12 00:24:01.685927 ignition[1339]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Jul 12 00:24:01.689462 ignition[1339]: INFO : PUT result: OK Jul 12 00:24:01.693812 ignition[1339]: DEBUG : files: compiled without relabeling support, skipping Jul 12 00:24:01.699761 ignition[1339]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Jul 12 00:24:01.699761 ignition[1339]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Jul 12 00:24:01.718588 ignition[1339]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Jul 12 00:24:01.722026 ignition[1339]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Jul 12 00:24:01.726499 unknown[1339]: wrote ssh authorized keys file for user: core Jul 12 00:24:01.728888 ignition[1339]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Jul 12 00:24:01.732524 ignition[1339]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/eks/bootstrap.sh" Jul 12 00:24:01.738771 ignition[1339]: INFO : oem config not found in "/usr/share/oem", looking on oem partition Jul 12 00:24:01.754028 ignition[1339]: INFO : op(1): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem1738949531" Jul 12 00:24:01.757202 ignition[1339]: CRITICAL : op(1): [failed] mounting "/dev/disk/by-label/OEM" at "/mnt/oem1738949531": device or resource busy Jul 12 00:24:01.757202 ignition[1339]: ERROR : failed to mount ext4 device "/dev/disk/by-label/OEM" at "/mnt/oem1738949531", trying btrfs: device or resource busy Jul 12 00:24:01.757202 ignition[1339]: INFO : op(2): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem1738949531" Jul 12 00:24:01.767896 ignition[1339]: INFO : op(2): [finished] mounting "/dev/disk/by-label/OEM" at "/mnt/oem1738949531" Jul 12 00:24:01.786389 ignition[1339]: INFO : op(3): [started] unmounting "/mnt/oem1738949531" Jul 12 00:24:01.788826 ignition[1339]: INFO : op(3): [finished] unmounting "/mnt/oem1738949531" Jul 12 00:24:01.791229 ignition[1339]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/eks/bootstrap.sh" Jul 12 00:24:01.795150 ignition[1339]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Jul 12 00:24:01.799139 ignition[1339]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Jul 12 00:24:01.802987 ignition[1339]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing file "/sysroot/etc/amazon/ssm/amazon-ssm-agent.json" Jul 12 00:24:01.809047 ignition[1339]: INFO : oem config not found in "/usr/share/oem", looking on oem partition Jul 12 00:24:01.820667 ignition[1339]: INFO : op(4): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem2548385944" Jul 12 00:24:01.827859 ignition[1339]: CRITICAL : op(4): [failed] mounting "/dev/disk/by-label/OEM" at "/mnt/oem2548385944": device or resource busy Jul 12 00:24:01.827859 ignition[1339]: ERROR : failed to mount ext4 device "/dev/disk/by-label/OEM" at "/mnt/oem2548385944", trying btrfs: device or resource busy Jul 12 00:24:01.827859 ignition[1339]: INFO : op(5): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem2548385944" Jul 12 00:24:01.827859 ignition[1339]: INFO : op(5): [finished] mounting "/dev/disk/by-label/OEM" at "/mnt/oem2548385944" Jul 12 00:24:01.827859 ignition[1339]: INFO : op(6): [started] unmounting "/mnt/oem2548385944" Jul 12 00:24:01.827859 ignition[1339]: INFO : op(6): [finished] unmounting "/mnt/oem2548385944" Jul 12 00:24:01.827859 ignition[1339]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing file "/sysroot/etc/amazon/ssm/amazon-ssm-agent.json" Jul 12 00:24:01.827859 ignition[1339]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/etc/amazon/ssm/seelog.xml" Jul 12 00:24:01.827859 ignition[1339]: INFO : oem config not found in "/usr/share/oem", looking on oem partition Jul 12 00:24:01.866840 ignition[1339]: INFO : op(7): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem399808114" Jul 12 00:24:01.866840 ignition[1339]: CRITICAL : op(7): [failed] mounting "/dev/disk/by-label/OEM" at "/mnt/oem399808114": device or resource busy Jul 12 00:24:01.866840 ignition[1339]: ERROR : failed to mount ext4 device "/dev/disk/by-label/OEM" at "/mnt/oem399808114", trying btrfs: device or resource busy Jul 12 00:24:01.866840 ignition[1339]: INFO : op(8): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem399808114" Jul 12 00:24:01.866840 ignition[1339]: INFO : op(8): [finished] mounting "/dev/disk/by-label/OEM" at "/mnt/oem399808114" Jul 12 00:24:01.866840 ignition[1339]: INFO : op(9): [started] unmounting "/mnt/oem399808114" Jul 12 00:24:01.866840 ignition[1339]: INFO : op(9): [finished] unmounting "/mnt/oem399808114" Jul 12 00:24:01.866840 ignition[1339]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/etc/amazon/ssm/seelog.xml" Jul 12 00:24:01.897494 ignition[1339]: INFO : files: createFilesystemsFiles: createFiles: op(7): [started] writing file "/sysroot/etc/systemd/system/nvidia.service" Jul 12 00:24:01.897494 ignition[1339]: INFO : oem config not found in "/usr/share/oem", looking on oem partition Jul 12 00:24:01.913085 ignition[1339]: INFO : op(a): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem2345497959" Jul 12 00:24:01.913085 ignition[1339]: CRITICAL : op(a): [failed] mounting "/dev/disk/by-label/OEM" at "/mnt/oem2345497959": device or resource busy Jul 12 00:24:01.913085 ignition[1339]: ERROR : failed to mount ext4 device "/dev/disk/by-label/OEM" at "/mnt/oem2345497959", trying btrfs: device or resource busy Jul 12 00:24:01.913085 ignition[1339]: INFO : op(b): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem2345497959" Jul 12 00:24:01.913085 ignition[1339]: INFO : op(b): [finished] mounting "/dev/disk/by-label/OEM" at "/mnt/oem2345497959" Jul 12 00:24:01.913085 ignition[1339]: INFO : op(c): [started] unmounting "/mnt/oem2345497959" Jul 12 00:24:01.913085 ignition[1339]: INFO : op(c): [finished] unmounting "/mnt/oem2345497959" Jul 12 00:24:01.913085 ignition[1339]: INFO : files: createFilesystemsFiles: createFiles: op(7): [finished] writing file "/sysroot/etc/systemd/system/nvidia.service" Jul 12 00:24:01.913085 ignition[1339]: INFO : files: op(8): [started] processing unit "nvidia.service" Jul 12 00:24:01.913085 ignition[1339]: INFO : files: op(8): [finished] processing unit "nvidia.service" Jul 12 00:24:01.913085 ignition[1339]: INFO : files: op(9): [started] processing unit "coreos-metadata-sshkeys@.service" Jul 12 00:24:01.913085 ignition[1339]: INFO : files: op(9): [finished] processing unit "coreos-metadata-sshkeys@.service" Jul 12 00:24:01.913085 ignition[1339]: INFO : files: op(a): [started] processing unit "amazon-ssm-agent.service" Jul 12 00:24:01.913085 ignition[1339]: INFO : files: op(a): op(b): [started] writing unit "amazon-ssm-agent.service" at "/sysroot/etc/systemd/system/amazon-ssm-agent.service" Jul 12 00:24:01.913085 ignition[1339]: INFO : files: op(a): op(b): [finished] writing unit "amazon-ssm-agent.service" at "/sysroot/etc/systemd/system/amazon-ssm-agent.service" Jul 12 00:24:01.913085 ignition[1339]: INFO : files: op(a): [finished] processing unit "amazon-ssm-agent.service" Jul 12 00:24:01.913085 ignition[1339]: INFO : files: op(c): [started] processing unit "etcd-member.service" Jul 12 00:24:01.913085 ignition[1339]: 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" Jul 12 00:24:01.913085 ignition[1339]: 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" Jul 12 00:24:01.913085 ignition[1339]: INFO : files: op(c): [finished] processing unit "etcd-member.service" Jul 12 00:24:02.002112 kernel: audit: type=1130 audit(1752279841.916: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' Jul 12 00:24:01.916000 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' Jul 12 00:24:01.917321 systemd[1]: Finished ignition-files.service. Jul 12 00:24:02.004193 ignition[1339]: INFO : files: op(e): [started] setting preset to enabled for "nvidia.service" Jul 12 00:24:02.004193 ignition[1339]: INFO : files: op(e): [finished] setting preset to enabled for "nvidia.service" Jul 12 00:24:02.004193 ignition[1339]: INFO : files: op(f): [started] setting preset to enabled for "coreos-metadata-sshkeys@.service " Jul 12 00:24:02.004193 ignition[1339]: INFO : files: op(f): [finished] setting preset to enabled for "coreos-metadata-sshkeys@.service " Jul 12 00:24:02.004193 ignition[1339]: INFO : files: op(10): [started] setting preset to enabled for "amazon-ssm-agent.service" Jul 12 00:24:02.004193 ignition[1339]: INFO : files: op(10): [finished] setting preset to enabled for "amazon-ssm-agent.service" Jul 12 00:24:02.004193 ignition[1339]: INFO : files: op(11): [started] setting preset to enabled for "etcd-member.service" Jul 12 00:24:02.004193 ignition[1339]: INFO : files: op(11): [finished] setting preset to enabled for "etcd-member.service" Jul 12 00:24:02.004193 ignition[1339]: INFO : files: createResultFile: createFiles: op(12): [started] writing file "/sysroot/etc/.ignition-result.json" Jul 12 00:24:02.004193 ignition[1339]: INFO : files: createResultFile: createFiles: op(12): [finished] writing file "/sysroot/etc/.ignition-result.json" Jul 12 00:24:02.004193 ignition[1339]: INFO : files: files passed Jul 12 00:24:02.004193 ignition[1339]: INFO : Ignition finished successfully Jul 12 00:24:02.072684 kernel: audit: type=1130 audit(1752279842.029: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' Jul 12 00:24:02.029000 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' Jul 12 00:24:02.036000 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' Jul 12 00:24:02.042000 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' Jul 12 00:24:01.967033 systemd-networkd[1184]: eth0: Gained IPv6LL Jul 12 00:24:01.971446 systemd[1]: Starting initrd-setup-root-after-ignition.service... Jul 12 00:24:02.081091 initrd-setup-root-after-ignition[1364]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jul 12 00:24:01.979184 systemd[1]: torcx-profile-populate.service was skipped because of an unmet condition check (ConditionPathExists=/sysroot/etc/torcx/next-profile). Jul 12 00:24:01.983754 systemd[1]: Starting ignition-quench.service... Jul 12 00:24:02.019484 systemd[1]: ignition-quench.service: Deactivated successfully. Jul 12 00:24:02.019680 systemd[1]: Finished ignition-quench.service. Jul 12 00:24:02.038900 systemd[1]: Finished initrd-setup-root-after-ignition.service. Jul 12 00:24:02.046098 systemd[1]: Reached target ignition-complete.target. Jul 12 00:24:02.049302 systemd[1]: Starting initrd-parse-etc.service... Jul 12 00:24:02.106431 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Jul 12 00:24:02.106849 systemd[1]: Finished initrd-parse-etc.service. Jul 12 00:24:02.110000 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' Jul 12 00:24:02.110000 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' Jul 12 00:24:02.112609 systemd[1]: Reached target initrd-fs.target. Jul 12 00:24:02.117015 systemd[1]: Reached target initrd.target. Jul 12 00:24:02.120199 systemd[1]: dracut-mount.service was skipped because no trigger condition checks were met. Jul 12 00:24:02.124236 systemd[1]: Starting dracut-pre-pivot.service... Jul 12 00:24:02.145424 systemd[1]: Finished dracut-pre-pivot.service. Jul 12 00:24:02.147000 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' Jul 12 00:24:02.150309 systemd[1]: Starting initrd-cleanup.service... Jul 12 00:24:02.178363 systemd[1]: initrd-cleanup.service: Deactivated successfully. Jul 12 00:24:02.178782 systemd[1]: Finished initrd-cleanup.service. Jul 12 00:24:02.181000 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' Jul 12 00:24:02.181000 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' Jul 12 00:24:02.184311 systemd[1]: Stopped target nss-lookup.target. Jul 12 00:24:02.187756 systemd[1]: Stopped target remote-cryptsetup.target. Jul 12 00:24:02.191912 systemd[1]: Stopped target timers.target. Jul 12 00:24:02.195203 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Jul 12 00:24:02.195407 systemd[1]: Stopped dracut-pre-pivot.service. Jul 12 00:24:02.197000 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' Jul 12 00:24:02.201056 systemd[1]: Stopped target initrd.target. Jul 12 00:24:02.204165 systemd[1]: Stopped target basic.target. Jul 12 00:24:02.207685 systemd[1]: Stopped target ignition-complete.target. Jul 12 00:24:02.211465 systemd[1]: Stopped target ignition-diskful.target. Jul 12 00:24:02.215241 systemd[1]: Stopped target initrd-root-device.target. Jul 12 00:24:02.219050 systemd[1]: Stopped target remote-fs.target. Jul 12 00:24:02.222463 systemd[1]: Stopped target remote-fs-pre.target. Jul 12 00:24:02.225988 systemd[1]: Stopped target sysinit.target. Jul 12 00:24:02.229265 systemd[1]: Stopped target local-fs.target. Jul 12 00:24:02.232548 systemd[1]: Stopped target local-fs-pre.target. Jul 12 00:24:02.235979 systemd[1]: Stopped target swap.target. Jul 12 00:24:02.239096 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Jul 12 00:24:02.239322 systemd[1]: Stopped dracut-pre-mount.service. Jul 12 00:24:02.242000 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' Jul 12 00:24:02.244631 systemd[1]: Stopped target cryptsetup.target. Jul 12 00:24:02.247950 systemd[1]: dracut-initqueue.service: Deactivated successfully. Jul 12 00:24:02.248143 systemd[1]: Stopped dracut-initqueue.service. Jul 12 00:24:02.251000 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' Jul 12 00:24:02.253804 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Jul 12 00:24:02.254375 systemd[1]: Stopped initrd-setup-root-after-ignition.service. Jul 12 00:24:02.258000 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' Jul 12 00:24:02.260372 systemd[1]: ignition-files.service: Deactivated successfully. Jul 12 00:24:02.260560 systemd[1]: Stopped ignition-files.service. Jul 12 00:24:02.264000 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' Jul 12 00:24:02.267225 systemd[1]: Stopping ignition-mount.service... Jul 12 00:24:02.271124 systemd[1]: Stopping iscsiuio.service... Jul 12 00:24:02.275492 systemd[1]: Stopping sysroot-boot.service... Jul 12 00:24:02.284504 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Jul 12 00:24:02.287064 systemd[1]: Stopped systemd-udev-trigger.service. Jul 12 00:24:02.289000 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' Jul 12 00:24:02.295132 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Jul 12 00:24:02.295000 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' Jul 12 00:24:02.295238 systemd[1]: Stopped dracut-pre-trigger.service. Jul 12 00:24:02.303373 systemd[1]: iscsiuio.service: Deactivated successfully. Jul 12 00:24:02.304574 systemd[1]: Stopped iscsiuio.service. Jul 12 00:24:02.307000 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' Jul 12 00:24:02.312614 ignition[1377]: INFO : Ignition 2.14.0 Jul 12 00:24:02.312614 ignition[1377]: INFO : Stage: umount Jul 12 00:24:02.316458 ignition[1377]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign" Jul 12 00:24:02.316458 ignition[1377]: DEBUG : parsing config with SHA512: 6629d8e825d60c9c9d4629d8547ef9a0b839d6b01b7f61a481a1f23308c924b8b0bbf10cae7f7fe3bcaf88b23d1a81baa7771c3670728d4d2a1e665216a1de7b Jul 12 00:24:02.334158 systemd[1]: sysroot-boot.mount: Deactivated successfully. Jul 12 00:24:02.344605 ignition[1377]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Jul 12 00:24:02.350759 ignition[1377]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Jul 12 00:24:02.350678 systemd[1]: sysroot-boot.service: Deactivated successfully. Jul 12 00:24:02.355508 ignition[1377]: INFO : PUT result: OK Jul 12 00:24:02.350895 systemd[1]: Stopped sysroot-boot.service. Jul 12 00:24:02.359000 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' Jul 12 00:24:02.365528 ignition[1377]: INFO : umount: umount passed Jul 12 00:24:02.368071 ignition[1377]: INFO : Ignition finished successfully Jul 12 00:24:02.370548 systemd[1]: ignition-mount.service: Deactivated successfully. Jul 12 00:24:02.370000 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' Jul 12 00:24:02.374000 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' Jul 12 00:24:02.377000 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' Jul 12 00:24:02.378000 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' Jul 12 00:24:02.370765 systemd[1]: Stopped ignition-mount.service. Jul 12 00:24:02.373563 systemd[1]: ignition-disks.service: Deactivated successfully. Jul 12 00:24:02.383000 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' Jul 12 00:24:02.373656 systemd[1]: Stopped ignition-disks.service. Jul 12 00:24:02.376404 systemd[1]: ignition-kargs.service: Deactivated successfully. Jul 12 00:24:02.376483 systemd[1]: Stopped ignition-kargs.service. Jul 12 00:24:02.378268 systemd[1]: ignition-fetch.service: Deactivated successfully. Jul 12 00:24:02.378344 systemd[1]: Stopped ignition-fetch.service. Jul 12 00:24:02.380173 systemd[1]: Stopped target network.target. Jul 12 00:24:02.381911 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Jul 12 00:24:02.407000 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' Jul 12 00:24:02.409000 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' Jul 12 00:24:02.381997 systemd[1]: Stopped ignition-fetch-offline.service. Jul 12 00:24:02.385885 systemd[1]: Stopped target paths.target. Jul 12 00:24:02.389005 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Jul 12 00:24:02.389255 systemd[1]: Stopped systemd-ask-password-console.path. Jul 12 00:24:02.390826 systemd[1]: Stopped target slices.target. Jul 12 00:24:02.394497 systemd[1]: Stopped target sockets.target. Jul 12 00:24:02.399208 systemd[1]: iscsid.socket: Deactivated successfully. Jul 12 00:24:02.399264 systemd[1]: Closed iscsid.socket. Jul 12 00:24:02.402751 systemd[1]: iscsiuio.socket: Deactivated successfully. Jul 12 00:24:02.402829 systemd[1]: Closed iscsiuio.socket. Jul 12 00:24:02.406092 systemd[1]: ignition-setup.service: Deactivated successfully. Jul 12 00:24:02.406426 systemd[1]: Stopped ignition-setup.service. Jul 12 00:24:02.409139 systemd[1]: initrd-setup-root.service: Deactivated successfully. Jul 12 00:24:02.409218 systemd[1]: Stopped initrd-setup-root.service. Jul 12 00:24:02.411331 systemd[1]: Stopping systemd-networkd.service... Jul 12 00:24:02.422645 systemd[1]: Stopping systemd-resolved.service... Jul 12 00:24:02.426153 systemd-networkd[1184]: eth0: DHCPv6 lease lost Jul 12 00:24:02.439471 systemd[1]: systemd-resolved.service: Deactivated successfully. Jul 12 00:24:02.441137 systemd[1]: Stopped systemd-resolved.service. Jul 12 00:24:02.452000 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' Jul 12 00:24:02.453000 audit: BPF prog-id=6 op=UNLOAD Jul 12 00:24:02.454995 systemd[1]: systemd-networkd.service: Deactivated successfully. Jul 12 00:24:02.457340 systemd[1]: Stopped systemd-networkd.service. Jul 12 00:24:02.458000 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' Jul 12 00:24:02.461168 systemd[1]: systemd-networkd.socket: Deactivated successfully. Jul 12 00:24:02.461253 systemd[1]: Closed systemd-networkd.socket. Jul 12 00:24:02.464000 audit: BPF prog-id=9 op=UNLOAD Jul 12 00:24:02.467704 systemd[1]: Stopping network-cleanup.service... Jul 12 00:24:02.472863 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Jul 12 00:24:02.474000 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' Jul 12 00:24:02.476000 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' Jul 12 00:24:02.473000 systemd[1]: Stopped parse-ip-for-networkd.service. Jul 12 00:24:02.479000 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' Jul 12 00:24:02.476573 systemd[1]: systemd-sysctl.service: Deactivated successfully. Jul 12 00:24:02.476658 systemd[1]: Stopped systemd-sysctl.service. Jul 12 00:24:02.480592 systemd[1]: systemd-modules-load.service: Deactivated successfully. Jul 12 00:24:02.480676 systemd[1]: Stopped systemd-modules-load.service. Jul 12 00:24:02.488308 systemd[1]: Stopping systemd-udevd.service... Jul 12 00:24:02.493230 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Jul 12 00:24:02.511091 systemd[1]: network-cleanup.service: Deactivated successfully. Jul 12 00:24:02.512621 systemd[1]: Stopped network-cleanup.service. Jul 12 00:24:02.515000 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' Jul 12 00:24:02.519943 systemd[1]: systemd-udevd.service: Deactivated successfully. Jul 12 00:24:02.522116 systemd[1]: Stopped systemd-udevd.service. Jul 12 00:24:02.523000 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' Jul 12 00:24:02.525737 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Jul 12 00:24:02.525831 systemd[1]: Closed systemd-udevd-control.socket. Jul 12 00:24:02.531288 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Jul 12 00:24:02.531367 systemd[1]: Closed systemd-udevd-kernel.socket. Jul 12 00:24:02.535130 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Jul 12 00:24:02.536816 systemd[1]: Stopped dracut-pre-udev.service. Jul 12 00:24:02.538000 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' Jul 12 00:24:02.542081 systemd[1]: dracut-cmdline.service: Deactivated successfully. Jul 12 00:24:02.540000 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' Jul 12 00:24:02.542179 systemd[1]: Stopped dracut-cmdline.service. Jul 12 00:24:02.547501 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jul 12 00:24:02.547597 systemd[1]: Stopped dracut-cmdline-ask.service. Jul 12 00:24:02.549000 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' Jul 12 00:24:02.554231 systemd[1]: Starting initrd-udevadm-cleanup-db.service... Jul 12 00:24:02.568233 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Jul 12 00:24:02.568000 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' Jul 12 00:24:02.568345 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service. Jul 12 00:24:02.572645 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Jul 12 00:24:02.574000 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' Jul 12 00:24:02.572749 systemd[1]: Stopped kmod-static-nodes.service. Jul 12 00:24:02.576366 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 12 00:24:02.576810 systemd[1]: Stopped systemd-vconsole-setup.service. Jul 12 00:24:02.584000 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' Jul 12 00:24:02.587983 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Jul 12 00:24:02.589126 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Jul 12 00:24:02.591000 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' Jul 12 00:24:02.591000 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' Jul 12 00:24:02.589353 systemd[1]: Finished initrd-udevadm-cleanup-db.service. Jul 12 00:24:02.595371 systemd[1]: Reached target initrd-switch-root.target. Jul 12 00:24:02.602819 systemd[1]: Starting initrd-switch-root.service... Jul 12 00:24:02.618451 systemd[1]: Switching root. Jul 12 00:24:02.646100 iscsid[1189]: iscsid shutting down. Jul 12 00:24:02.647844 systemd-journald[309]: Received SIGTERM from PID 1 (systemd). Jul 12 00:24:02.647917 systemd-journald[309]: Journal stopped Jul 12 00:24:07.342300 kernel: SELinux: Class mctp_socket not defined in policy. Jul 12 00:24:07.342411 kernel: SELinux: Class anon_inode not defined in policy. Jul 12 00:24:07.342444 kernel: SELinux: the above unknown classes and permissions will be allowed Jul 12 00:24:07.342484 kernel: SELinux: policy capability network_peer_controls=1 Jul 12 00:24:07.342516 kernel: SELinux: policy capability open_perms=1 Jul 12 00:24:07.342549 kernel: SELinux: policy capability extended_socket_class=1 Jul 12 00:24:07.342580 kernel: SELinux: policy capability always_check_network=0 Jul 12 00:24:07.342610 kernel: SELinux: policy capability cgroup_seclabel=1 Jul 12 00:24:07.342645 kernel: SELinux: policy capability nnp_nosuid_transition=1 Jul 12 00:24:07.342674 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Jul 12 00:24:07.342704 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Jul 12 00:24:07.342757 systemd[1]: Successfully loaded SELinux policy in 72.303ms. Jul 12 00:24:07.342816 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 20.323ms. Jul 12 00:24:07.342849 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) Jul 12 00:24:07.342882 systemd[1]: Detected virtualization amazon. Jul 12 00:24:07.342934 systemd[1]: Detected architecture arm64. Jul 12 00:24:07.342969 systemd[1]: Detected first boot. Jul 12 00:24:07.343001 systemd[1]: Initializing machine ID from VM UUID. Jul 12 00:24:07.343036 kernel: SELinux: Context system_u:object_r:container_file_t:s0:c1022,c1023 is not valid (left unmapped). Jul 12 00:24:07.343632 systemd[1]: Populated /etc with preset unit settings. Jul 12 00:24:07.343685 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Jul 12 00:24:07.343839 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Jul 12 00:24:07.343881 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. Jul 12 00:24:07.343922 kernel: kauditd_printk_skb: 56 callbacks suppressed Jul 12 00:24:07.343951 kernel: audit: type=1334 audit(1752279846.798:88): prog-id=12 op=LOAD Jul 12 00:24:07.343985 kernel: audit: type=1334 audit(1752279846.798:89): prog-id=3 op=UNLOAD Jul 12 00:24:07.344015 kernel: audit: type=1334 audit(1752279846.800:90): prog-id=13 op=LOAD Jul 12 00:24:07.344046 systemd[1]: iscsid.service: Deactivated successfully. Jul 12 00:24:07.344172 kernel: audit: type=1334 audit(1752279846.803:91): prog-id=14 op=LOAD Jul 12 00:24:07.344211 systemd[1]: Stopped iscsid.service. Jul 12 00:24:07.344243 kernel: audit: type=1334 audit(1752279846.803:92): prog-id=4 op=UNLOAD Jul 12 00:24:07.344278 kernel: audit: type=1334 audit(1752279846.803:93): prog-id=5 op=UNLOAD Jul 12 00:24:07.344309 kernel: audit: type=1131 audit(1752279846.806:94): 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' Jul 12 00:24:07.344338 kernel: audit: type=1334 audit(1752279846.815:95): prog-id=12 op=UNLOAD Jul 12 00:24:07.344367 kernel: audit: type=1131 audit(1752279846.832:96): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 12 00:24:07.344398 systemd[1]: initrd-switch-root.service: Deactivated successfully. Jul 12 00:24:07.344429 systemd[1]: Stopped initrd-switch-root.service. Jul 12 00:24:07.344458 kernel: audit: type=1130 audit(1752279846.847:97): 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' Jul 12 00:24:07.344499 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Jul 12 00:24:07.344532 systemd[1]: Created slice system-addon\x2dconfig.slice. Jul 12 00:24:07.344567 systemd[1]: Created slice system-addon\x2drun.slice. Jul 12 00:24:07.344598 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice. Jul 12 00:24:07.344664 systemd[1]: Created slice system-getty.slice. Jul 12 00:24:07.362443 systemd[1]: Created slice system-modprobe.slice. Jul 12 00:24:07.362491 systemd[1]: Created slice system-serial\x2dgetty.slice. Jul 12 00:24:07.362535 systemd[1]: Created slice system-system\x2dcloudinit.slice. Jul 12 00:24:07.362574 systemd[1]: Created slice system-systemd\x2dfsck.slice. Jul 12 00:24:07.362604 systemd[1]: Created slice user.slice. Jul 12 00:24:07.362634 systemd[1]: Started systemd-ask-password-console.path. Jul 12 00:24:07.362663 systemd[1]: Started systemd-ask-password-wall.path. Jul 12 00:24:07.362694 systemd[1]: Set up automount boot.automount. Jul 12 00:24:07.362753 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount. Jul 12 00:24:07.362787 systemd[1]: Stopped target initrd-switch-root.target. Jul 12 00:24:07.362820 systemd[1]: Stopped target initrd-fs.target. Jul 12 00:24:07.362851 systemd[1]: Stopped target initrd-root-fs.target. Jul 12 00:24:07.362887 systemd[1]: Reached target integritysetup.target. Jul 12 00:24:07.362916 systemd[1]: Reached target remote-cryptsetup.target. Jul 12 00:24:07.362948 systemd[1]: Reached target remote-fs.target. Jul 12 00:24:07.362979 systemd[1]: Reached target slices.target. Jul 12 00:24:07.363010 systemd[1]: Reached target swap.target. Jul 12 00:24:07.363038 systemd[1]: Reached target torcx.target. Jul 12 00:24:07.363069 systemd[1]: Reached target veritysetup.target. Jul 12 00:24:07.363098 systemd[1]: Listening on systemd-coredump.socket. Jul 12 00:24:07.363127 systemd[1]: Listening on systemd-initctl.socket. Jul 12 00:24:07.363158 systemd[1]: Listening on systemd-networkd.socket. Jul 12 00:24:07.363192 systemd[1]: Listening on systemd-udevd-control.socket. Jul 12 00:24:07.363221 systemd[1]: Listening on systemd-udevd-kernel.socket. Jul 12 00:24:07.363250 systemd[1]: Listening on systemd-userdbd.socket. Jul 12 00:24:07.363279 systemd[1]: Mounting dev-hugepages.mount... Jul 12 00:24:07.363308 systemd[1]: Mounting dev-mqueue.mount... Jul 12 00:24:07.363339 systemd[1]: Mounting media.mount... Jul 12 00:24:07.363368 systemd[1]: Mounting sys-kernel-debug.mount... Jul 12 00:24:07.363397 systemd[1]: Mounting sys-kernel-tracing.mount... Jul 12 00:24:07.363425 systemd[1]: Mounting tmp.mount... Jul 12 00:24:07.363460 systemd[1]: Starting flatcar-tmpfiles.service... Jul 12 00:24:07.363491 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Jul 12 00:24:07.364152 systemd[1]: Starting kmod-static-nodes.service... Jul 12 00:24:07.364187 systemd[1]: Starting modprobe@configfs.service... Jul 12 00:24:07.364217 systemd[1]: Starting modprobe@dm_mod.service... Jul 12 00:24:07.366115 systemd[1]: Starting modprobe@drm.service... Jul 12 00:24:07.366169 systemd[1]: Starting modprobe@efi_pstore.service... Jul 12 00:24:07.366202 systemd[1]: Starting modprobe@fuse.service... Jul 12 00:24:07.366231 systemd[1]: Starting modprobe@loop.service... Jul 12 00:24:07.366269 systemd[1]: setup-nsswitch.service was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Jul 12 00:24:07.366301 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Jul 12 00:24:07.366333 systemd[1]: Stopped systemd-fsck-root.service. Jul 12 00:24:07.366364 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Jul 12 00:24:07.366395 systemd[1]: Stopped systemd-fsck-usr.service. Jul 12 00:24:07.366424 systemd[1]: Stopped systemd-journald.service. Jul 12 00:24:07.366454 systemd[1]: Starting systemd-journald.service... Jul 12 00:24:07.366483 systemd[1]: Starting systemd-modules-load.service... Jul 12 00:24:07.366512 systemd[1]: Starting systemd-network-generator.service... Jul 12 00:24:07.366545 systemd[1]: Starting systemd-remount-fs.service... Jul 12 00:24:07.366576 systemd[1]: Starting systemd-udev-trigger.service... Jul 12 00:24:07.366607 systemd[1]: verity-setup.service: Deactivated successfully. Jul 12 00:24:07.366636 systemd[1]: Stopped verity-setup.service. Jul 12 00:24:07.366665 systemd[1]: Mounted dev-hugepages.mount. Jul 12 00:24:07.366694 systemd[1]: Mounted dev-mqueue.mount. Jul 12 00:24:07.366756 systemd[1]: Mounted media.mount. Jul 12 00:24:07.366792 systemd[1]: Mounted sys-kernel-debug.mount. Jul 12 00:24:07.366821 systemd[1]: Mounted sys-kernel-tracing.mount. Jul 12 00:24:07.366855 systemd[1]: Mounted tmp.mount. Jul 12 00:24:07.366884 systemd[1]: Finished kmod-static-nodes.service. Jul 12 00:24:07.366914 systemd[1]: modprobe@configfs.service: Deactivated successfully. Jul 12 00:24:07.366944 systemd[1]: Finished modprobe@configfs.service. Jul 12 00:24:07.366974 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 12 00:24:07.367008 systemd[1]: Finished modprobe@dm_mod.service. Jul 12 00:24:07.367043 kernel: fuse: init (API version 7.34) Jul 12 00:24:07.367072 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 12 00:24:07.367111 systemd[1]: Finished modprobe@drm.service. Jul 12 00:24:07.367143 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 12 00:24:07.367173 systemd[1]: Finished modprobe@efi_pstore.service. Jul 12 00:24:07.367202 systemd[1]: modprobe@fuse.service: Deactivated successfully. Jul 12 00:24:07.367233 kernel: loop: module loaded Jul 12 00:24:07.367262 systemd[1]: Finished modprobe@fuse.service. Jul 12 00:24:07.367295 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 12 00:24:07.367324 systemd[1]: Finished modprobe@loop.service. Jul 12 00:24:07.367353 systemd[1]: Finished systemd-modules-load.service. Jul 12 00:24:07.367382 systemd[1]: Finished systemd-network-generator.service. Jul 12 00:24:07.367413 systemd[1]: Finished flatcar-tmpfiles.service. Jul 12 00:24:07.367447 systemd[1]: Finished systemd-remount-fs.service. Jul 12 00:24:07.367478 systemd[1]: Reached target network-pre.target. Jul 12 00:24:07.367509 systemd[1]: Mounting sys-fs-fuse-connections.mount... Jul 12 00:24:07.367544 systemd[1]: Mounting sys-kernel-config.mount... Jul 12 00:24:07.367576 systemd[1]: remount-root.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Jul 12 00:24:07.367606 systemd[1]: Starting systemd-hwdb-update.service... Jul 12 00:24:07.367636 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 12 00:24:07.367666 systemd[1]: Starting systemd-random-seed.service... Jul 12 00:24:07.367701 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Jul 12 00:24:07.367767 systemd[1]: Starting systemd-sysctl.service... Jul 12 00:24:07.367800 systemd[1]: Starting systemd-sysusers.service... Jul 12 00:24:07.367829 systemd[1]: Mounted sys-fs-fuse-connections.mount. Jul 12 00:24:07.367866 systemd-journald[1489]: Journal started Jul 12 00:24:07.367962 systemd-journald[1489]: Runtime Journal (/run/log/journal/ec2d07447f3aa9ac1a8ad7e3c5f524cd) is 8.0M, max 75.4M, 67.4M free. Jul 12 00:24:03.008000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Jul 12 00:24:03.119000 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 Jul 12 00:24:03.120000 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 Jul 12 00:24:03.120000 audit: BPF prog-id=10 op=LOAD Jul 12 00:24:03.120000 audit: BPF prog-id=10 op=UNLOAD Jul 12 00:24:03.120000 audit: BPF prog-id=11 op=LOAD Jul 12 00:24:03.120000 audit: BPF prog-id=11 op=UNLOAD Jul 12 00:24:03.252000 audit[1410]: AVC avc: denied { associate } for pid=1410 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" Jul 12 00:24:03.252000 audit[1410]: SYSCALL arch=c00000b7 syscall=5 success=yes exit=0 a0=40001858cc a1=4000028e40 a2=4000027100 a3=32 items=0 ppid=1393 pid=1410 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) Jul 12 00:24:03.252000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Jul 12 00:24:03.257000 audit[1410]: AVC avc: denied { associate } for pid=1410 comm="torcx-generator" name="bin" scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Jul 12 00:24:03.257000 audit[1410]: SYSCALL arch=c00000b7 syscall=34 success=yes exit=0 a0=ffffffffffffff9c a1=40001859a5 a2=1ed a3=0 items=2 ppid=1393 pid=1410 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) Jul 12 00:24:03.257000 audit: CWD cwd="/" Jul 12 00:24:03.257000 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 Jul 12 00:24:03.257000 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 Jul 12 00:24:03.257000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Jul 12 00:24:06.798000 audit: BPF prog-id=12 op=LOAD Jul 12 00:24:06.798000 audit: BPF prog-id=3 op=UNLOAD Jul 12 00:24:06.800000 audit: BPF prog-id=13 op=LOAD Jul 12 00:24:06.803000 audit: BPF prog-id=14 op=LOAD Jul 12 00:24:06.803000 audit: BPF prog-id=4 op=UNLOAD Jul 12 00:24:06.803000 audit: BPF prog-id=5 op=UNLOAD Jul 12 00:24:06.806000 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' Jul 12 00:24:06.815000 audit: BPF prog-id=12 op=UNLOAD Jul 12 00:24:06.832000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 12 00:24:06.847000 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' Jul 12 00:24:06.847000 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' Jul 12 00:24:07.389894 systemd[1]: Started systemd-journald.service. Jul 12 00:24:07.105000 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' Jul 12 00:24:07.109000 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' Jul 12 00:24:07.116000 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' Jul 12 00:24:07.116000 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' Jul 12 00:24:07.118000 audit: BPF prog-id=15 op=LOAD Jul 12 00:24:07.118000 audit: BPF prog-id=16 op=LOAD Jul 12 00:24:07.118000 audit: BPF prog-id=17 op=LOAD Jul 12 00:24:07.118000 audit: BPF prog-id=13 op=UNLOAD Jul 12 00:24:07.118000 audit: BPF prog-id=14 op=UNLOAD Jul 12 00:24:07.164000 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' Jul 12 00:24:07.200000 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' Jul 12 00:24:07.209000 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' Jul 12 00:24:07.209000 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' Jul 12 00:24:07.220000 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' Jul 12 00:24:07.220000 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' Jul 12 00:24:07.231000 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' Jul 12 00:24:07.231000 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' Jul 12 00:24:07.239000 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' Jul 12 00:24:07.239000 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' Jul 12 00:24:07.252000 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' Jul 12 00:24:07.252000 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' Jul 12 00:24:07.262000 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' Jul 12 00:24:07.262000 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' Jul 12 00:24:07.267000 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' Jul 12 00:24:07.270000 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' Jul 12 00:24:07.277000 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' Jul 12 00:24:07.282000 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' Jul 12 00:24:07.338000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Jul 12 00:24:07.338000 audit[1489]: SYSCALL arch=c00000b7 syscall=211 success=yes exit=60 a0=3 a1=ffffcd4eafd0 a2=4000 a3=1 items=0 ppid=1 pid=1489 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) Jul 12 00:24:07.338000 audit: PROCTITLE proctitle="/usr/lib/systemd/systemd-journald" Jul 12 00:24:07.371000 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' Jul 12 00:24:07.392000 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' Jul 12 00:24:06.795665 systemd[1]: Queued start job for default target multi-user.target. Jul 12 00:24:03.248442 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2025-07-12T00:24:03Z" 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.7 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.7 /var/lib/torcx/store]" Jul 12 00:24:06.795687 systemd[1]: Unnecessary job was removed for dev-nvme0n1p6.device. Jul 12 00:24:03.249400 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2025-07-12T00:24:03Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Jul 12 00:24:06.807964 systemd[1]: systemd-journald.service: Deactivated successfully. Jul 12 00:24:03.249449 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2025-07-12T00:24:03Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Jul 12 00:24:07.373125 systemd[1]: Mounted sys-kernel-config.mount. Jul 12 00:24:03.249512 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2025-07-12T00:24:03Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Jul 12 00:24:07.377185 systemd[1]: Starting systemd-journal-flush.service... Jul 12 00:24:03.249537 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2025-07-12T00:24:03Z" level=debug msg="skipped missing lower profile" missing profile=oem Jul 12 00:24:07.393959 systemd[1]: Finished systemd-random-seed.service. Jul 12 00:24:03.249597 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2025-07-12T00:24:03Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Jul 12 00:24:07.396129 systemd[1]: Reached target first-boot-complete.target. Jul 12 00:24:03.249627 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2025-07-12T00:24:03Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Jul 12 00:24:03.250055 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2025-07-12T00:24:03Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Jul 12 00:24:07.412942 systemd-journald[1489]: Time spent on flushing to /var/log/journal/ec2d07447f3aa9ac1a8ad7e3c5f524cd is 58.759ms for 1103 entries. Jul 12 00:24:07.412942 systemd-journald[1489]: System Journal (/var/log/journal/ec2d07447f3aa9ac1a8ad7e3c5f524cd) is 8.0M, max 195.6M, 187.6M free. Jul 12 00:24:07.500952 systemd-journald[1489]: Received client request to flush runtime journal. Jul 12 00:24:07.441000 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' Jul 12 00:24:07.468000 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' Jul 12 00:24:03.250140 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2025-07-12T00:24:03Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Jul 12 00:24:07.441078 systemd[1]: Finished systemd-sysctl.service. Jul 12 00:24:03.250176 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2025-07-12T00:24:03Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Jul 12 00:24:07.467382 systemd[1]: Finished systemd-sysusers.service. Jul 12 00:24:03.252754 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2025-07-12T00:24:03Z" 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 Jul 12 00:24:07.471990 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... Jul 12 00:24:03.252997 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2025-07-12T00:24:03Z" 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 Jul 12 00:24:03.253047 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2025-07-12T00:24:03Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3510.3.7: no such file or directory" path=/usr/share/oem/torcx/store/3510.3.7 Jul 12 00:24:07.502847 systemd[1]: Finished systemd-journal-flush.service. Jul 12 00:24:03.253088 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2025-07-12T00:24:03Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Jul 12 00:24:03.253139 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2025-07-12T00:24:03Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3510.3.7: no such file or directory" path=/var/lib/torcx/store/3510.3.7 Jul 12 00:24:07.503000 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' Jul 12 00:24:03.253179 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2025-07-12T00:24:03Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Jul 12 00:24:06.019838 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2025-07-12T00:24:06Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Jul 12 00:24:06.020372 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2025-07-12T00:24:06Z" 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 Jul 12 00:24:06.020603 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2025-07-12T00:24:06Z" 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 Jul 12 00:24:06.021109 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2025-07-12T00:24:06Z" 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 Jul 12 00:24:06.021220 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2025-07-12T00:24:06Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Jul 12 00:24:06.021356 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2025-07-12T00:24:06Z" 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 Jul 12 00:24:07.543234 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. Jul 12 00:24:07.544000 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' Jul 12 00:24:07.562062 systemd[1]: Finished systemd-udev-trigger.service. Jul 12 00:24:07.563000 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' Jul 12 00:24:07.566321 systemd[1]: Starting systemd-udev-settle.service... Jul 12 00:24:07.581264 udevadm[1531]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Jul 12 00:24:08.202000 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' Jul 12 00:24:08.203000 audit: BPF prog-id=18 op=LOAD Jul 12 00:24:08.203000 audit: BPF prog-id=19 op=LOAD Jul 12 00:24:08.203000 audit: BPF prog-id=7 op=UNLOAD Jul 12 00:24:08.203000 audit: BPF prog-id=8 op=UNLOAD Jul 12 00:24:08.201713 systemd[1]: Finished systemd-hwdb-update.service. Jul 12 00:24:08.206090 systemd[1]: Starting systemd-udevd.service... Jul 12 00:24:08.245338 systemd-udevd[1532]: Using default interface naming scheme 'v252'. Jul 12 00:24:08.290990 systemd[1]: Started systemd-udevd.service. Jul 12 00:24:08.291000 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' Jul 12 00:24:08.293000 audit: BPF prog-id=20 op=LOAD Jul 12 00:24:08.296061 systemd[1]: Starting systemd-networkd.service... Jul 12 00:24:08.304000 audit: BPF prog-id=21 op=LOAD Jul 12 00:24:08.304000 audit: BPF prog-id=22 op=LOAD Jul 12 00:24:08.304000 audit: BPF prog-id=23 op=LOAD Jul 12 00:24:08.307774 systemd[1]: Starting systemd-userdbd.service... Jul 12 00:24:08.378867 systemd[1]: Condition check resulted in dev-ttyS0.device being skipped. Jul 12 00:24:08.398363 systemd[1]: Started systemd-userdbd.service. Jul 12 00:24:08.399000 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' Jul 12 00:24:08.405400 (udev-worker)[1534]: Network interface NamePolicy= disabled on kernel command line. Jul 12 00:24:08.557879 systemd-networkd[1537]: lo: Link UP Jul 12 00:24:08.557903 systemd-networkd[1537]: lo: Gained carrier Jul 12 00:24:08.558859 systemd-networkd[1537]: Enumeration completed Jul 12 00:24:08.559028 systemd[1]: Started systemd-networkd.service. Jul 12 00:24:08.559000 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' Jul 12 00:24:08.563393 systemd[1]: Starting systemd-networkd-wait-online.service... Jul 12 00:24:08.564385 systemd-networkd[1537]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 12 00:24:08.579762 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Jul 12 00:24:08.581060 systemd-networkd[1537]: eth0: Link UP Jul 12 00:24:08.581431 systemd-networkd[1537]: eth0: Gained carrier Jul 12 00:24:08.591974 systemd-networkd[1537]: eth0: DHCPv4 address 172.31.18.187/20, gateway 172.31.16.1 acquired from 172.31.16.1 Jul 12 00:24:08.756242 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Jul 12 00:24:08.759000 systemd[1]: Finished systemd-udev-settle.service. Jul 12 00:24:08.759000 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' Jul 12 00:24:08.763290 systemd[1]: Starting lvm2-activation-early.service... Jul 12 00:24:08.846366 lvm[1648]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Jul 12 00:24:08.880361 systemd[1]: Finished lvm2-activation-early.service. Jul 12 00:24:08.881000 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' Jul 12 00:24:08.882765 systemd[1]: Reached target cryptsetup.target. Jul 12 00:24:08.886927 systemd[1]: Starting lvm2-activation.service... Jul 12 00:24:08.896123 lvm[1649]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Jul 12 00:24:08.932504 systemd[1]: Finished lvm2-activation.service. Jul 12 00:24:08.935000 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' Jul 12 00:24:08.937224 systemd[1]: Reached target local-fs-pre.target. Jul 12 00:24:08.939295 systemd[1]: var-lib-machines.mount was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Jul 12 00:24:08.939351 systemd[1]: Reached target local-fs.target. Jul 12 00:24:08.941273 systemd[1]: Reached target machines.target. Jul 12 00:24:08.945378 systemd[1]: Starting ldconfig.service... Jul 12 00:24:08.948708 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Jul 12 00:24:08.949112 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). Jul 12 00:24:08.952122 systemd[1]: Starting systemd-boot-update.service... Jul 12 00:24:08.957256 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service... Jul 12 00:24:08.965899 systemd[1]: Starting systemd-machine-id-commit.service... Jul 12 00:24:08.968497 systemd[1]: systemd-sysext.service was skipped because no trigger condition checks were met. Jul 12 00:24:08.968621 systemd[1]: ensure-sysext.service was skipped because no trigger condition checks were met. Jul 12 00:24:08.972067 systemd[1]: Starting systemd-tmpfiles-setup.service... Jul 12 00:24:08.980232 systemd[1]: boot.automount: Got automount request for /boot, triggered by 1651 (bootctl) Jul 12 00:24:08.985053 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service... Jul 12 00:24:09.010037 systemd-tmpfiles[1654]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Jul 12 00:24:09.011643 systemd-tmpfiles[1654]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Jul 12 00:24:09.014989 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Jul 12 00:24:09.017441 systemd[1]: Finished systemd-machine-id-commit.service. Jul 12 00:24:09.015000 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' Jul 12 00:24:09.020546 systemd-tmpfiles[1654]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Jul 12 00:24:09.028510 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service. Jul 12 00:24:09.026000 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' Jul 12 00:24:09.125863 systemd-fsck[1659]: fsck.fat 4.2 (2021-01-31) Jul 12 00:24:09.125863 systemd-fsck[1659]: /dev/nvme0n1p1: 236 files, 117310/258078 clusters Jul 12 00:24:09.129000 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' Jul 12 00:24:09.128044 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service. Jul 12 00:24:09.133144 systemd[1]: Mounting boot.mount... Jul 12 00:24:09.159458 systemd[1]: Mounted boot.mount. Jul 12 00:24:09.191124 systemd[1]: Finished systemd-boot-update.service. Jul 12 00:24:09.191000 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' Jul 12 00:24:09.352180 systemd[1]: Finished systemd-tmpfiles-setup.service. Jul 12 00:24:09.353000 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' Jul 12 00:24:09.357279 systemd[1]: Starting audit-rules.service... Jul 12 00:24:09.361779 systemd[1]: Starting clean-ca-certificates.service... Jul 12 00:24:09.367056 systemd[1]: Starting systemd-journal-catalog-update.service... Jul 12 00:24:09.371000 audit: BPF prog-id=24 op=LOAD Jul 12 00:24:09.377033 systemd[1]: Starting systemd-resolved.service... Jul 12 00:24:09.379000 audit: BPF prog-id=25 op=LOAD Jul 12 00:24:09.383211 systemd[1]: Starting systemd-timesyncd.service... Jul 12 00:24:09.387512 systemd[1]: Starting systemd-update-utmp.service... Jul 12 00:24:09.410669 systemd[1]: Finished clean-ca-certificates.service. Jul 12 00:24:09.409000 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' Jul 12 00:24:09.413006 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Jul 12 00:24:09.420000 audit[1679]: SYSTEM_BOOT pid=1679 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' Jul 12 00:24:09.427535 systemd[1]: Finished systemd-update-utmp.service. Jul 12 00:24:09.428000 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' Jul 12 00:24:09.467371 systemd[1]: Finished systemd-journal-catalog-update.service. Jul 12 00:24:09.468000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journal-catalog-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 12 00:24:09.513000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Jul 12 00:24:09.513000 audit[1693]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffed414940 a2=420 a3=0 items=0 ppid=1673 pid=1693 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) Jul 12 00:24:09.513000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573 Jul 12 00:24:09.515716 augenrules[1693]: No rules Jul 12 00:24:09.517998 systemd[1]: Finished audit-rules.service. Jul 12 00:24:09.546099 systemd[1]: Started systemd-timesyncd.service. Jul 12 00:24:09.548228 systemd[1]: Reached target time-set.target. Jul 12 00:24:09.584383 systemd-resolved[1677]: Positive Trust Anchors: Jul 12 00:24:09.585336 systemd-resolved[1677]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 12 00:24:09.585496 systemd-resolved[1677]: 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 Jul 12 00:24:09.616842 systemd-resolved[1677]: Defaulting to hostname 'linux'. Jul 12 00:24:09.619985 systemd[1]: Started systemd-resolved.service. Jul 12 00:24:09.622084 systemd[1]: Reached target network.target. Jul 12 00:24:09.623855 systemd[1]: Reached target nss-lookup.target. Jul 12 00:24:09.632573 systemd-timesyncd[1678]: Contacted time server 162.159.200.123:123 (0.flatcar.pool.ntp.org). Jul 12 00:24:09.632862 systemd-timesyncd[1678]: Initial clock synchronization to Sat 2025-07-12 00:24:09.392639 UTC. Jul 12 00:24:09.679338 ldconfig[1650]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jul 12 00:24:09.689872 systemd[1]: Finished ldconfig.service. Jul 12 00:24:09.694117 systemd[1]: Starting systemd-update-done.service... Jul 12 00:24:09.708701 systemd[1]: Finished systemd-update-done.service. Jul 12 00:24:09.710936 systemd[1]: Reached target sysinit.target. Jul 12 00:24:09.712917 systemd[1]: Started motdgen.path. Jul 12 00:24:09.714535 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path. Jul 12 00:24:09.717253 systemd[1]: Started logrotate.timer. Jul 12 00:24:09.719095 systemd[1]: Started mdadm.timer. Jul 12 00:24:09.720636 systemd[1]: Started systemd-tmpfiles-clean.timer. Jul 12 00:24:09.722568 systemd[1]: update-engine-stub.timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Jul 12 00:24:09.722637 systemd[1]: Reached target paths.target. Jul 12 00:24:09.724271 systemd[1]: Reached target timers.target. Jul 12 00:24:09.727226 systemd[1]: Listening on dbus.socket. Jul 12 00:24:09.730859 systemd[1]: Starting docker.socket... Jul 12 00:24:09.737712 systemd[1]: Listening on sshd.socket. Jul 12 00:24:09.739609 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). Jul 12 00:24:09.740464 systemd[1]: Listening on docker.socket. Jul 12 00:24:09.742256 systemd[1]: Reached target sockets.target. Jul 12 00:24:09.744016 systemd[1]: Reached target basic.target. Jul 12 00:24:09.745810 systemd[1]: addon-config@usr-share-oem.service was skipped because no trigger condition checks were met. Jul 12 00:24:09.745877 systemd[1]: addon-run@usr-share-oem.service was skipped because no trigger condition checks were met. Jul 12 00:24:09.747940 systemd[1]: Starting containerd.service... Jul 12 00:24:09.751943 systemd[1]: Starting coreos-metadata-sshkeys@core.service... Jul 12 00:24:09.759865 systemd[1]: Starting coreos-metadata.service... Jul 12 00:24:09.765280 systemd[1]: Starting dbus.service... Jul 12 00:24:09.772547 systemd[1]: Starting enable-oem-cloudinit.service... Jul 12 00:24:09.777320 systemd[1]: Starting extend-filesystems.service... Jul 12 00:24:09.779637 systemd[1]: flatcar-setup-environment.service was skipped because of an unmet condition check (ConditionPathExists=/usr/share/oem/bin/flatcar-setup-environment). Jul 12 00:24:09.783619 systemd[1]: Starting motdgen.service... Jul 12 00:24:09.789386 systemd[1]: Starting ssh-key-proc-cmdline.service... Jul 12 00:24:09.798145 systemd[1]: Starting sshd-keygen.service... Jul 12 00:24:09.808162 systemd[1]: Starting systemd-logind.service... Jul 12 00:24:09.810301 systemd[1]: systemd-pcrphase.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Jul 12 00:24:09.810426 systemd[1]: tcsd.service was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Jul 12 00:24:09.918009 jq[1717]: true Jul 12 00:24:09.932583 jq[1706]: false Jul 12 00:24:09.811312 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Jul 12 00:24:09.812899 systemd[1]: Starting update-engine.service... Jul 12 00:24:09.817193 systemd[1]: Starting update-ssh-keys-after-ignition.service... Jul 12 00:24:09.835883 systemd-networkd[1537]: eth0: Gained IPv6LL Jul 12 00:24:09.838983 systemd[1]: Finished systemd-networkd-wait-online.service. Jul 12 00:24:09.841446 systemd[1]: Reached target network-online.target. Jul 12 00:24:09.845628 systemd[1]: Started amazon-ssm-agent.service. Jul 12 00:24:09.850973 systemd[1]: Started nvidia.service. Jul 12 00:24:09.931918 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Jul 12 00:24:09.932269 systemd[1]: Condition check resulted in enable-oem-cloudinit.service being skipped. Jul 12 00:24:09.938332 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Jul 12 00:24:09.938677 systemd[1]: Finished ssh-key-proc-cmdline.service. Jul 12 00:24:09.981702 jq[1725]: true Jul 12 00:24:10.003875 systemd[1]: Started dbus.service. Jul 12 00:24:10.003439 dbus-daemon[1705]: [system] SELinux support is enabled Jul 12 00:24:10.009662 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). Jul 12 00:24:10.009779 systemd[1]: Reached target system-config.target. Jul 12 00:24:10.011965 systemd[1]: user-cloudinit-proc-cmdline.service was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Jul 12 00:24:10.012023 systemd[1]: Reached target user-config.target. Jul 12 00:24:10.046637 extend-filesystems[1707]: Found nvme0n1 Jul 12 00:24:10.048882 extend-filesystems[1707]: Found nvme0n1p1 Jul 12 00:24:10.048882 extend-filesystems[1707]: Found nvme0n1p2 Jul 12 00:24:10.048882 extend-filesystems[1707]: Found nvme0n1p3 Jul 12 00:24:10.048882 extend-filesystems[1707]: Found usr Jul 12 00:24:10.048882 extend-filesystems[1707]: Found nvme0n1p4 Jul 12 00:24:10.048882 extend-filesystems[1707]: Found nvme0n1p6 Jul 12 00:24:10.048882 extend-filesystems[1707]: Found nvme0n1p7 Jul 12 00:24:10.048882 extend-filesystems[1707]: Found nvme0n1p9 Jul 12 00:24:10.048882 extend-filesystems[1707]: Checking size of /dev/nvme0n1p9 Jul 12 00:24:10.063123 dbus-daemon[1705]: [system] Activating systemd to hand-off: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.1' (uid=244 pid=1537 comm="/usr/lib/systemd/systemd-networkd" label="system_u:system_r:kernel_t:s0") Jul 12 00:24:10.074575 systemd[1]: Starting systemd-hostnamed.service... Jul 12 00:24:10.067305 dbus-daemon[1705]: [system] Successfully activated service 'org.freedesktop.systemd1' Jul 12 00:24:10.101960 systemd[1]: motdgen.service: Deactivated successfully. Jul 12 00:24:10.102290 systemd[1]: Finished motdgen.service. Jul 12 00:24:10.173950 extend-filesystems[1707]: Resized partition /dev/nvme0n1p9 Jul 12 00:24:10.192269 extend-filesystems[1766]: resize2fs 1.46.5 (30-Dec-2021) Jul 12 00:24:10.208096 bash[1765]: Updated "/home/core/.ssh/authorized_keys" Jul 12 00:24:10.210961 systemd[1]: Finished update-ssh-keys-after-ignition.service. Jul 12 00:24:10.247796 kernel: EXT4-fs (nvme0n1p9): resizing filesystem from 553472 to 1489915 blocks Jul 12 00:24:10.266609 update_engine[1716]: I0712 00:24:10.265794 1716 main.cc:92] Flatcar Update Engine starting Jul 12 00:24:10.281523 systemd[1]: Started update-engine.service. Jul 12 00:24:10.286399 systemd[1]: Started locksmithd.service. Jul 12 00:24:10.288741 update_engine[1716]: I0712 00:24:10.288677 1716 update_check_scheduler.cc:74] Next update check in 5m50s Jul 12 00:24:10.314173 amazon-ssm-agent[1722]: 2025/07/12 00:24:10 Failed to load instance info from vault. RegistrationKey does not exist. Jul 12 00:24:10.329801 kernel: EXT4-fs (nvme0n1p9): resized filesystem to 1489915 Jul 12 00:24:10.347759 amazon-ssm-agent[1722]: Initializing new seelog logger Jul 12 00:24:10.348796 amazon-ssm-agent[1722]: New Seelog Logger Creation Complete Jul 12 00:24:10.348796 amazon-ssm-agent[1722]: 2025/07/12 00:24:10 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Jul 12 00:24:10.348796 amazon-ssm-agent[1722]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Jul 12 00:24:10.348796 amazon-ssm-agent[1722]: 2025/07/12 00:24:10 processing appconfig overrides Jul 12 00:24:10.350970 extend-filesystems[1766]: Filesystem at /dev/nvme0n1p9 is mounted on /; on-line resizing required Jul 12 00:24:10.350970 extend-filesystems[1766]: old_desc_blocks = 1, new_desc_blocks = 1 Jul 12 00:24:10.350970 extend-filesystems[1766]: The filesystem on /dev/nvme0n1p9 is now 1489915 (4k) blocks long. Jul 12 00:24:10.362638 extend-filesystems[1707]: Resized filesystem in /dev/nvme0n1p9 Jul 12 00:24:10.356887 systemd[1]: extend-filesystems.service: Deactivated successfully. Jul 12 00:24:10.357242 systemd[1]: Finished extend-filesystems.service. Jul 12 00:24:10.391577 env[1721]: time="2025-07-12T00:24:10.391482975Z" level=info msg="starting containerd" revision=92b3a9d6f1b3bcc6dc74875cfdea653fe39f09c2 version=1.6.16 Jul 12 00:24:10.408253 systemd[1]: nvidia.service: Deactivated successfully. Jul 12 00:24:10.475815 dbus-daemon[1705]: [system] Successfully activated service 'org.freedesktop.hostname1' Jul 12 00:24:10.476034 systemd[1]: Started systemd-hostnamed.service. Jul 12 00:24:10.478856 dbus-daemon[1705]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.5' (uid=0 pid=1747 comm="/usr/lib/systemd/systemd-hostnamed" label="system_u:system_r:kernel_t:s0") Jul 12 00:24:10.482894 systemd[1]: Starting polkit.service... Jul 12 00:24:10.490673 systemd-logind[1715]: Watching system buttons on /dev/input/event0 (Power Button) Jul 12 00:24:10.490772 systemd-logind[1715]: Watching system buttons on /dev/input/event1 (Sleep Button) Jul 12 00:24:10.502884 systemd-logind[1715]: New seat seat0. Jul 12 00:24:10.520894 systemd[1]: Started systemd-logind.service. Jul 12 00:24:10.527359 env[1721]: time="2025-07-12T00:24:10.527285019Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Jul 12 00:24:10.528092 env[1721]: time="2025-07-12T00:24:10.528049583Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Jul 12 00:24:10.531738 env[1721]: time="2025-07-12T00:24:10.531644860Z" 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.186-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Jul 12 00:24:10.532890 env[1721]: time="2025-07-12T00:24:10.532841807Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Jul 12 00:24:10.533714 env[1721]: time="2025-07-12T00:24:10.533655936Z" 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 Jul 12 00:24:10.534169 env[1721]: time="2025-07-12T00:24:10.534133169Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Jul 12 00:24:10.534306 env[1721]: time="2025-07-12T00:24:10.534275473Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" Jul 12 00:24:10.534443 env[1721]: time="2025-07-12T00:24:10.534415076Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Jul 12 00:24:10.535089 env[1721]: time="2025-07-12T00:24:10.535043495Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Jul 12 00:24:10.536573 polkitd[1791]: Started polkitd version 121 Jul 12 00:24:10.537715 env[1721]: time="2025-07-12T00:24:10.537615953Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Jul 12 00:24:10.538453 env[1721]: time="2025-07-12T00:24:10.538380576Z" 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 Jul 12 00:24:10.539039 env[1721]: time="2025-07-12T00:24:10.538996795Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Jul 12 00:24:10.539351 env[1721]: time="2025-07-12T00:24:10.539304777Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" Jul 12 00:24:10.540360 env[1721]: time="2025-07-12T00:24:10.540307411Z" level=info msg="metadata content store policy set" policy=shared Jul 12 00:24:10.551751 env[1721]: time="2025-07-12T00:24:10.551387346Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Jul 12 00:24:10.551751 env[1721]: time="2025-07-12T00:24:10.551484218Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Jul 12 00:24:10.551751 env[1721]: time="2025-07-12T00:24:10.551542757Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Jul 12 00:24:10.551751 env[1721]: time="2025-07-12T00:24:10.551672105Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Jul 12 00:24:10.551751 env[1721]: time="2025-07-12T00:24:10.551752750Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Jul 12 00:24:10.552111 env[1721]: time="2025-07-12T00:24:10.551790116Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Jul 12 00:24:10.552111 env[1721]: time="2025-07-12T00:24:10.551822173Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Jul 12 00:24:10.552654 env[1721]: time="2025-07-12T00:24:10.552344327Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Jul 12 00:24:10.552654 env[1721]: time="2025-07-12T00:24:10.552403576Z" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1 Jul 12 00:24:10.552654 env[1721]: time="2025-07-12T00:24:10.552440232Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Jul 12 00:24:10.552654 env[1721]: time="2025-07-12T00:24:10.552470567Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Jul 12 00:24:10.552654 env[1721]: time="2025-07-12T00:24:10.552500063Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Jul 12 00:24:10.552964 env[1721]: time="2025-07-12T00:24:10.552762601Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Jul 12 00:24:10.552964 env[1721]: time="2025-07-12T00:24:10.552942073Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Jul 12 00:24:10.553356 env[1721]: time="2025-07-12T00:24:10.553310223Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Jul 12 00:24:10.553445 env[1721]: time="2025-07-12T00:24:10.553371568Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Jul 12 00:24:10.553445 env[1721]: time="2025-07-12T00:24:10.553405826Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Jul 12 00:24:10.553972 env[1721]: time="2025-07-12T00:24:10.553622174Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Jul 12 00:24:10.553972 env[1721]: time="2025-07-12T00:24:10.553658108Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Jul 12 00:24:10.553972 env[1721]: time="2025-07-12T00:24:10.553705019Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Jul 12 00:24:10.553972 env[1721]: time="2025-07-12T00:24:10.553755282Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Jul 12 00:24:10.553972 env[1721]: time="2025-07-12T00:24:10.553787445Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Jul 12 00:24:10.553972 env[1721]: time="2025-07-12T00:24:10.553821458Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Jul 12 00:24:10.553972 env[1721]: time="2025-07-12T00:24:10.553851083Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Jul 12 00:24:10.553972 env[1721]: time="2025-07-12T00:24:10.553880417Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Jul 12 00:24:10.553972 env[1721]: time="2025-07-12T00:24:10.553914837Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Jul 12 00:24:10.554903 env[1721]: time="2025-07-12T00:24:10.554210096Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Jul 12 00:24:10.554903 env[1721]: time="2025-07-12T00:24:10.554249976Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Jul 12 00:24:10.554903 env[1721]: time="2025-07-12T00:24:10.554280276Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Jul 12 00:24:10.554903 env[1721]: time="2025-07-12T00:24:10.554308737Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Jul 12 00:24:10.554903 env[1721]: time="2025-07-12T00:24:10.554340212Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1 Jul 12 00:24:10.554903 env[1721]: time="2025-07-12T00:24:10.554369418Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Jul 12 00:24:10.554903 env[1721]: time="2025-07-12T00:24:10.554406120Z" level=error msg="failed to initialize a tracing processor \"otlp\"" error="no OpenTelemetry endpoint: skip plugin" Jul 12 00:24:10.554903 env[1721]: time="2025-07-12T00:24:10.554469747Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Jul 12 00:24:10.556317 env[1721]: time="2025-07-12T00:24:10.554815467Z" 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}" Jul 12 00:24:10.556317 env[1721]: time="2025-07-12T00:24:10.554914270Z" level=info msg="Connect containerd service" Jul 12 00:24:10.556317 env[1721]: time="2025-07-12T00:24:10.554971250Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Jul 12 00:24:10.577427 env[1721]: time="2025-07-12T00:24:10.577347726Z" 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" Jul 12 00:24:10.581448 env[1721]: time="2025-07-12T00:24:10.577809535Z" level=info msg="Start subscribing containerd event" Jul 12 00:24:10.581448 env[1721]: time="2025-07-12T00:24:10.577924414Z" level=info msg="Start recovering state" Jul 12 00:24:10.581448 env[1721]: time="2025-07-12T00:24:10.577956274Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jul 12 00:24:10.581448 env[1721]: time="2025-07-12T00:24:10.578047802Z" level=info msg=serving... address=/run/containerd/containerd.sock Jul 12 00:24:10.581448 env[1721]: time="2025-07-12T00:24:10.578082712Z" level=info msg="Start event monitor" Jul 12 00:24:10.581448 env[1721]: time="2025-07-12T00:24:10.578150226Z" level=info msg="containerd successfully booted in 0.206196s" Jul 12 00:24:10.581448 env[1721]: time="2025-07-12T00:24:10.580387580Z" level=info msg="Start snapshots syncer" Jul 12 00:24:10.581448 env[1721]: time="2025-07-12T00:24:10.580430312Z" level=info msg="Start cni network conf syncer for default" Jul 12 00:24:10.581448 env[1721]: time="2025-07-12T00:24:10.580479633Z" level=info msg="Start streaming server" Jul 12 00:24:10.578285 systemd[1]: Started containerd.service. Jul 12 00:24:10.597614 polkitd[1791]: Loading rules from directory /etc/polkit-1/rules.d Jul 12 00:24:10.597755 polkitd[1791]: Loading rules from directory /usr/share/polkit-1/rules.d Jul 12 00:24:10.610738 polkitd[1791]: Finished loading, compiling and executing 2 rules Jul 12 00:24:10.613339 dbus-daemon[1705]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Jul 12 00:24:10.613583 systemd[1]: Started polkit.service. Jul 12 00:24:10.617175 polkitd[1791]: Acquired the name org.freedesktop.PolicyKit1 on the system bus Jul 12 00:24:10.666442 systemd-hostnamed[1747]: Hostname set to (transient) Jul 12 00:24:10.666589 systemd-resolved[1677]: System hostname changed to 'ip-172-31-18-187'. Jul 12 00:24:10.880150 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO Create new startup processor Jul 12 00:24:10.885794 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO [LongRunningPluginsManager] registered plugins: {} Jul 12 00:24:10.885953 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO Initializing bookkeeping folders Jul 12 00:24:10.886064 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO removing the completed state files Jul 12 00:24:10.886174 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO Initializing bookkeeping folders for long running plugins Jul 12 00:24:10.886304 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO Initializing replies folder for MDS reply requests that couldn't reach the service Jul 12 00:24:10.886421 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO Initializing healthcheck folders for long running plugins Jul 12 00:24:10.886528 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO Initializing locations for inventory plugin Jul 12 00:24:10.886646 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO Initializing default location for custom inventory Jul 12 00:24:10.886778 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO Initializing default location for file inventory Jul 12 00:24:10.886886 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO Initializing default location for role inventory Jul 12 00:24:10.886993 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO Init the cloudwatchlogs publisher Jul 12 00:24:10.887100 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO [instanceID=i-0355a1d2840170c9e] Successfully loaded platform independent plugin aws:softwareInventory Jul 12 00:24:10.887221 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO [instanceID=i-0355a1d2840170c9e] Successfully loaded platform independent plugin aws:updateSsmAgent Jul 12 00:24:10.887331 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO [instanceID=i-0355a1d2840170c9e] Successfully loaded platform independent plugin aws:refreshAssociation Jul 12 00:24:10.887439 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO [instanceID=i-0355a1d2840170c9e] Successfully loaded platform independent plugin aws:configurePackage Jul 12 00:24:10.887547 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO [instanceID=i-0355a1d2840170c9e] Successfully loaded platform independent plugin aws:runPowerShellScript Jul 12 00:24:10.887658 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO [instanceID=i-0355a1d2840170c9e] Successfully loaded platform independent plugin aws:configureDocker Jul 12 00:24:10.887793 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO [instanceID=i-0355a1d2840170c9e] Successfully loaded platform independent plugin aws:runDockerAction Jul 12 00:24:10.887926 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO [instanceID=i-0355a1d2840170c9e] Successfully loaded platform independent plugin aws:downloadContent Jul 12 00:24:10.888038 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO [instanceID=i-0355a1d2840170c9e] Successfully loaded platform independent plugin aws:runDocument Jul 12 00:24:10.888147 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO [instanceID=i-0355a1d2840170c9e] Successfully loaded platform dependent plugin aws:runShellScript Jul 12 00:24:10.888259 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO Starting Agent: amazon-ssm-agent - v2.3.1319.0 Jul 12 00:24:10.888369 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO OS: linux, Arch: arm64 Jul 12 00:24:10.889881 amazon-ssm-agent[1722]: datastore file /var/lib/amazon/ssm/i-0355a1d2840170c9e/longrunningplugins/datastore/store doesn't exist - no long running plugins to execute Jul 12 00:24:10.890506 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO [MessagingDeliveryService] Starting document processing engine... Jul 12 00:24:10.904264 coreos-metadata[1703]: Jul 12 00:24:10.904 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Jul 12 00:24:10.907122 coreos-metadata[1703]: Jul 12 00:24:10.907 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/public-keys: Attempt #1 Jul 12 00:24:10.907846 coreos-metadata[1703]: Jul 12 00:24:10.907 INFO Fetch successful Jul 12 00:24:10.907952 coreos-metadata[1703]: Jul 12 00:24:10.907 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/public-keys/0/openssh-key: Attempt #1 Jul 12 00:24:10.908771 coreos-metadata[1703]: Jul 12 00:24:10.908 INFO Fetch successful Jul 12 00:24:10.913129 coreos-metadata[1704]: Jul 12 00:24:10.912 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Jul 12 00:24:10.915014 unknown[1703]: wrote ssh authorized keys file for user: core Jul 12 00:24:10.916158 coreos-metadata[1704]: Jul 12 00:24:10.915 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/instance-id: Attempt #1 Jul 12 00:24:10.917656 coreos-metadata[1704]: Jul 12 00:24:10.917 INFO Fetch successful Jul 12 00:24:10.918052 coreos-metadata[1704]: Jul 12 00:24:10.917 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/instance-type: Attempt #1 Jul 12 00:24:10.918312 coreos-metadata[1704]: Jul 12 00:24:10.918 INFO Fetch successful Jul 12 00:24:10.919959 coreos-metadata[1704]: Jul 12 00:24:10.919 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/local-ipv4: Attempt #1 Jul 12 00:24:10.923850 coreos-metadata[1704]: Jul 12 00:24:10.920 INFO Fetch successful Jul 12 00:24:10.924153 coreos-metadata[1704]: Jul 12 00:24:10.923 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/public-ipv4: Attempt #1 Jul 12 00:24:10.924466 coreos-metadata[1704]: Jul 12 00:24:10.924 INFO Fetch successful Jul 12 00:24:10.924780 coreos-metadata[1704]: Jul 12 00:24:10.924 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/placement/availability-zone: Attempt #1 Jul 12 00:24:10.926496 coreos-metadata[1704]: Jul 12 00:24:10.926 INFO Fetch successful Jul 12 00:24:10.926496 coreos-metadata[1704]: Jul 12 00:24:10.926 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/hostname: Attempt #1 Jul 12 00:24:10.935400 coreos-metadata[1704]: Jul 12 00:24:10.935 INFO Fetch successful Jul 12 00:24:10.935400 coreos-metadata[1704]: Jul 12 00:24:10.935 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/public-hostname: Attempt #1 Jul 12 00:24:10.936541 coreos-metadata[1704]: Jul 12 00:24:10.936 INFO Fetch successful Jul 12 00:24:10.936858 coreos-metadata[1704]: Jul 12 00:24:10.936 INFO Fetching http://169.254.169.254/2019-10-01/dynamic/instance-identity/document: Attempt #1 Jul 12 00:24:10.937363 coreos-metadata[1704]: Jul 12 00:24:10.937 INFO Fetch successful Jul 12 00:24:10.949191 systemd[1]: Finished coreos-metadata-sshkeys@core.service. Jul 12 00:24:10.952659 update-ssh-keys[1880]: Updated "/home/core/.ssh/authorized_keys" Jul 12 00:24:10.972984 systemd[1]: Finished coreos-metadata.service. Jul 12 00:24:10.977784 systemd[1]: Starting etcd-member.service... Jul 12 00:24:11.014997 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO [MessagingDeliveryService] [EngineProcessor] Starting Jul 12 00:24:11.110218 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO [MessagingDeliveryService] [EngineProcessor] Initial processing Jul 12 00:24:11.138397 systemd[1]: Created slice system-sshd.slice. Jul 12 00:24:11.167012 systemd[1]: Starting docker.service... Jul 12 00:24:11.204731 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO [MessagingDeliveryService] Starting message polling Jul 12 00:24:11.304937 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO [MessagingDeliveryService] Starting send replies to MDS Jul 12 00:24:11.307982 env[1908]: time="2025-07-12T00:24:11.307926844Z" level=info msg="Starting up" Jul 12 00:24:11.321170 env[1908]: time="2025-07-12T00:24:11.319844812Z" level=info msg="parsed scheme: \"unix\"" module=grpc Jul 12 00:24:11.321170 env[1908]: time="2025-07-12T00:24:11.319885080Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc Jul 12 00:24:11.321170 env[1908]: time="2025-07-12T00:24:11.319922742Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/libcontainerd/docker-containerd.sock 0 }] }" module=grpc Jul 12 00:24:11.321170 env[1908]: time="2025-07-12T00:24:11.319944839Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc Jul 12 00:24:11.324642 env[1908]: time="2025-07-12T00:24:11.324279553Z" level=info msg="parsed scheme: \"unix\"" module=grpc Jul 12 00:24:11.324642 env[1908]: time="2025-07-12T00:24:11.324316222Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc Jul 12 00:24:11.324642 env[1908]: time="2025-07-12T00:24:11.324346370Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/libcontainerd/docker-containerd.sock 0 }] }" module=grpc Jul 12 00:24:11.324642 env[1908]: time="2025-07-12T00:24:11.324371283Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc Jul 12 00:24:11.337293 systemd[1]: var-lib-docker-check\x2doverlayfs\x2dsupport4132274655-merged.mount: Deactivated successfully. Jul 12 00:24:11.395927 env[1908]: time="2025-07-12T00:24:11.395370802Z" level=info msg="Loading containers: start." Jul 12 00:24:11.399868 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO [instanceID=i-0355a1d2840170c9e] Starting association polling Jul 12 00:24:11.418364 locksmithd[1780]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Jul 12 00:24:11.494976 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO [MessagingDeliveryService] [Association] [EngineProcessor] Starting Jul 12 00:24:11.590272 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO [MessagingDeliveryService] [Association] Launching response handler Jul 12 00:24:11.640752 kernel: Initializing XFRM netlink socket Jul 12 00:24:11.685228 env[1908]: time="2025-07-12T00:24:11.684317911Z" 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" Jul 12 00:24:11.688449 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO [MessagingDeliveryService] [Association] [EngineProcessor] Initial processing Jul 12 00:24:11.686359 (udev-worker)[1539]: Network interface NamePolicy= disabled on kernel command line. Jul 12 00:24:11.781972 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO [MessagingDeliveryService] [Association] Initializing association scheduling service Jul 12 00:24:11.818408 systemd-networkd[1537]: docker0: Link UP Jul 12 00:24:11.844974 env[1908]: time="2025-07-12T00:24:11.844929463Z" level=info msg="Loading containers: done." Jul 12 00:24:11.877802 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO [MessagingDeliveryService] [Association] Association scheduling service initialized Jul 12 00:24:11.885166 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck2517988194-merged.mount: Deactivated successfully. Jul 12 00:24:11.895231 env[1908]: time="2025-07-12T00:24:11.895158369Z" 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 Jul 12 00:24:11.895876 env[1908]: time="2025-07-12T00:24:11.895846812Z" level=info msg="Docker daemon" commit=112bdf3343 graphdriver(s)=overlay2 version=20.10.23 Jul 12 00:24:11.896234 env[1908]: time="2025-07-12T00:24:11.896208017Z" level=info msg="Daemon has completed initialization" Jul 12 00:24:11.928261 systemd[1]: Started docker.service. Jul 12 00:24:11.959341 env[1908]: time="2025-07-12T00:24:11.959196739Z" level=info msg="API listen on /run/docker.sock" Jul 12 00:24:11.961856 etcd-wrapper[1888]: Error response from daemon: No such container: etcd-member Jul 12 00:24:11.973876 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO [MessageGatewayService] Starting session document processing engine... Jul 12 00:24:12.009063 etcd-wrapper[2022]: Error: No such container: etcd-member Jul 12 00:24:12.070181 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO [MessageGatewayService] [EngineProcessor] Starting Jul 12 00:24:12.078278 etcd-wrapper[2038]: Unable to find image 'quay.io/coreos/etcd:v3.5.16' locally Jul 12 00:24:12.166647 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO [MessageGatewayService] SSM Agent is trying to setup control channel for Session Manager module. Jul 12 00:24:12.263484 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO [MessageGatewayService] Setting up websocket for controlchannel for instance: i-0355a1d2840170c9e, requestId: a59a2769-8324-4f14-b839-76d6aa1bc12c Jul 12 00:24:12.360317 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO [OfflineService] Starting document processing engine... Jul 12 00:24:12.457333 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO [OfflineService] [EngineProcessor] Starting Jul 12 00:24:12.554698 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO [OfflineService] [EngineProcessor] Initial processing Jul 12 00:24:12.652058 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO [OfflineService] Starting message polling Jul 12 00:24:12.749813 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO [OfflineService] Starting send replies to MDS Jul 12 00:24:12.847635 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO [LongRunningPluginsManager] starting long running plugin manager Jul 12 00:24:12.945641 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO [LongRunningPluginsManager] there aren't any long running plugin to execute Jul 12 00:24:13.043855 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO [HealthCheck] HealthCheck reporting agent health. Jul 12 00:24:13.142459 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO [LongRunningPluginsManager] There are no long running plugins currently getting executed - skipping their healthcheck Jul 12 00:24:13.240959 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO [MessageGatewayService] listening reply. Jul 12 00:24:13.313740 etcd-wrapper[2038]: v3.5.16: Pulling from coreos/etcd Jul 12 00:24:13.339867 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO [StartupProcessor] Executing startup processor tasks Jul 12 00:24:13.438962 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO [StartupProcessor] Write to serial port: Amazon SSM Agent v2.3.1319.0 is running Jul 12 00:24:13.538064 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO [StartupProcessor] Write to serial port: OsProductName: Flatcar Container Linux by Kinvolk Jul 12 00:24:13.573910 etcd-wrapper[2038]: d10f96ed9607: Pulling fs layer Jul 12 00:24:13.573910 etcd-wrapper[2038]: 2ae710cd8bfe: Pulling fs layer Jul 12 00:24:13.574127 etcd-wrapper[2038]: d462aa345367: Pulling fs layer Jul 12 00:24:13.574127 etcd-wrapper[2038]: 0f8b424aa0b9: Pulling fs layer Jul 12 00:24:13.574127 etcd-wrapper[2038]: d557676654e5: Pulling fs layer Jul 12 00:24:13.574127 etcd-wrapper[2038]: c8022d07192e: Pulling fs layer Jul 12 00:24:13.574127 etcd-wrapper[2038]: d858cbc252ad: Pulling fs layer Jul 12 00:24:13.574127 etcd-wrapper[2038]: 1069fc2daed1: Pulling fs layer Jul 12 00:24:13.574127 etcd-wrapper[2038]: b40161cd83fc: Pulling fs layer Jul 12 00:24:13.574127 etcd-wrapper[2038]: 5318d93a3a65: Pulling fs layer Jul 12 00:24:13.574127 etcd-wrapper[2038]: 307c1adadb60: Pulling fs layer Jul 12 00:24:13.574127 etcd-wrapper[2038]: f9b3c65df792: Pulling fs layer Jul 12 00:24:13.574127 etcd-wrapper[2038]: 5709b4a82c35: Pulling fs layer Jul 12 00:24:13.574127 etcd-wrapper[2038]: d5372e382b07: Pulling fs layer Jul 12 00:24:13.574127 etcd-wrapper[2038]: 9c3b5253dddc: Pulling fs layer Jul 12 00:24:13.574850 etcd-wrapper[2038]: 7a57323f7551: Pulling fs layer Jul 12 00:24:13.574850 etcd-wrapper[2038]: 0f8b424aa0b9: Waiting Jul 12 00:24:13.574850 etcd-wrapper[2038]: d557676654e5: Waiting Jul 12 00:24:13.574850 etcd-wrapper[2038]: c8022d07192e: Waiting Jul 12 00:24:13.574850 etcd-wrapper[2038]: d858cbc252ad: Waiting Jul 12 00:24:13.574850 etcd-wrapper[2038]: 1069fc2daed1: Waiting Jul 12 00:24:13.574850 etcd-wrapper[2038]: b40161cd83fc: Waiting Jul 12 00:24:13.574850 etcd-wrapper[2038]: 5318d93a3a65: Waiting Jul 12 00:24:13.574850 etcd-wrapper[2038]: 307c1adadb60: Waiting Jul 12 00:24:13.574850 etcd-wrapper[2038]: f9b3c65df792: Waiting Jul 12 00:24:13.574850 etcd-wrapper[2038]: 5709b4a82c35: Waiting Jul 12 00:24:13.574850 etcd-wrapper[2038]: d5372e382b07: Waiting Jul 12 00:24:13.574850 etcd-wrapper[2038]: 9c3b5253dddc: Waiting Jul 12 00:24:13.574850 etcd-wrapper[2038]: 7a57323f7551: Waiting Jul 12 00:24:13.637561 amazon-ssm-agent[1722]: 2025-07-12 00:24:10 INFO [StartupProcessor] Write to serial port: OsVersion: 3510.3.7 Jul 12 00:24:13.737198 amazon-ssm-agent[1722]: 2025-07-12 00:24:11 INFO [MessageGatewayService] Opening websocket connection to: wss://ssmmessages.us-west-2.amazonaws.com/v1/control-channel/i-0355a1d2840170c9e?role=subscribe&stream=input Jul 12 00:24:13.820150 etcd-wrapper[2038]: d10f96ed9607: Download complete Jul 12 00:24:13.837028 amazon-ssm-agent[1722]: 2025-07-12 00:24:11 INFO [MessageGatewayService] Successfully opened websocket connection to: wss://ssmmessages.us-west-2.amazonaws.com/v1/control-channel/i-0355a1d2840170c9e?role=subscribe&stream=input Jul 12 00:24:13.838664 etcd-wrapper[2038]: 2ae710cd8bfe: Verifying Checksum Jul 12 00:24:13.838664 etcd-wrapper[2038]: 2ae710cd8bfe: Download complete Jul 12 00:24:13.847285 etcd-wrapper[2038]: d462aa345367: Download complete Jul 12 00:24:13.936997 amazon-ssm-agent[1722]: 2025-07-12 00:24:11 INFO [MessageGatewayService] Starting receiving message from control channel Jul 12 00:24:13.957530 etcd-wrapper[2038]: d10f96ed9607: Pull complete Jul 12 00:24:14.037731 amazon-ssm-agent[1722]: 2025-07-12 00:24:11 INFO [MessageGatewayService] [EngineProcessor] Initial processing Jul 12 00:24:14.077928 systemd[1]: var-lib-docker-overlay2-adf97b2bf6e1a7656fc74307de84751c8f7bdc3cd510d9626347ad9b5cc10366-merged.mount: Deactivated successfully. Jul 12 00:24:14.080596 etcd-wrapper[2038]: 0f8b424aa0b9: Verifying Checksum Jul 12 00:24:14.080596 etcd-wrapper[2038]: 0f8b424aa0b9: Download complete Jul 12 00:24:14.080596 etcd-wrapper[2038]: d557676654e5: Verifying Checksum Jul 12 00:24:14.080596 etcd-wrapper[2038]: d557676654e5: Download complete Jul 12 00:24:14.114217 etcd-wrapper[2038]: 2ae710cd8bfe: Pull complete Jul 12 00:24:14.140401 etcd-wrapper[2038]: c8022d07192e: Verifying Checksum Jul 12 00:24:14.140594 etcd-wrapper[2038]: c8022d07192e: Download complete Jul 12 00:24:14.307404 etcd-wrapper[2038]: d858cbc252ad: Verifying Checksum Jul 12 00:24:14.307404 etcd-wrapper[2038]: d858cbc252ad: Download complete Jul 12 00:24:14.324390 etcd-wrapper[2038]: 1069fc2daed1: Verifying Checksum Jul 12 00:24:14.324390 etcd-wrapper[2038]: 1069fc2daed1: Download complete Jul 12 00:24:14.422264 etcd-wrapper[2038]: b40161cd83fc: Verifying Checksum Jul 12 00:24:14.422264 etcd-wrapper[2038]: b40161cd83fc: Download complete Jul 12 00:24:14.549944 etcd-wrapper[2038]: 5318d93a3a65: Verifying Checksum Jul 12 00:24:14.550770 etcd-wrapper[2038]: 5318d93a3a65: Download complete Jul 12 00:24:14.558459 sshd_keygen[1737]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Jul 12 00:24:14.563399 etcd-wrapper[2038]: 307c1adadb60: Verifying Checksum Jul 12 00:24:14.563399 etcd-wrapper[2038]: 307c1adadb60: Download complete Jul 12 00:24:14.609035 systemd[1]: Finished sshd-keygen.service. Jul 12 00:24:14.614376 systemd[1]: Starting issuegen.service... Jul 12 00:24:14.618637 systemd[1]: Started sshd@0-172.31.18.187:22-147.75.109.163:40450.service. Jul 12 00:24:14.632072 systemd[1]: issuegen.service: Deactivated successfully. Jul 12 00:24:14.632428 systemd[1]: Finished issuegen.service. Jul 12 00:24:14.637460 systemd[1]: Starting systemd-user-sessions.service... Jul 12 00:24:14.660079 systemd[1]: Finished systemd-user-sessions.service. Jul 12 00:24:14.664976 systemd[1]: Started getty@tty1.service. Jul 12 00:24:14.671020 systemd[1]: Started serial-getty@ttyS0.service. Jul 12 00:24:14.673506 systemd[1]: Reached target getty.target. Jul 12 00:24:14.783778 etcd-wrapper[2038]: f9b3c65df792: Verifying Checksum Jul 12 00:24:14.783778 etcd-wrapper[2038]: f9b3c65df792: Download complete Jul 12 00:24:14.848023 sshd[2074]: Accepted publickey for core from 147.75.109.163 port 40450 ssh2: RSA SHA256:hAayEOBHnTpwll2xPQSU8cSp7XCWn/pXChvPbqogNKA Jul 12 00:24:14.853907 sshd[2074]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 12 00:24:14.880945 systemd[1]: Created slice user-500.slice. Jul 12 00:24:14.886283 systemd[1]: Starting user-runtime-dir@500.service... Jul 12 00:24:14.894536 systemd-logind[1715]: New session 1 of user core. Jul 12 00:24:14.906353 systemd[1]: Finished user-runtime-dir@500.service. Jul 12 00:24:14.911669 systemd[1]: Starting user@500.service... Jul 12 00:24:14.922299 (systemd)[2082]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Jul 12 00:24:14.974834 etcd-wrapper[2038]: d5372e382b07: Verifying Checksum Jul 12 00:24:14.974988 etcd-wrapper[2038]: d5372e382b07: Download complete Jul 12 00:24:14.978532 etcd-wrapper[2038]: 5709b4a82c35: Verifying Checksum Jul 12 00:24:14.978687 etcd-wrapper[2038]: 5709b4a82c35: Download complete Jul 12 00:24:15.027771 etcd-wrapper[2038]: 9c3b5253dddc: Verifying Checksum Jul 12 00:24:15.027771 etcd-wrapper[2038]: 9c3b5253dddc: Download complete Jul 12 00:24:15.143201 systemd[1]: var-lib-docker-overlay2-6102e81ac831a25070f77b9443bff8ee7ed839bfdbd6d412cd617bb6821501e1-merged.mount: Deactivated successfully. Jul 12 00:24:15.148551 systemd[2082]: Queued start job for default target default.target. Jul 12 00:24:15.152418 systemd[2082]: Reached target paths.target. Jul 12 00:24:15.152471 systemd[2082]: Reached target sockets.target. Jul 12 00:24:15.152504 systemd[2082]: Reached target timers.target. Jul 12 00:24:15.152533 systemd[2082]: Reached target basic.target. Jul 12 00:24:15.152629 systemd[2082]: Reached target default.target. Jul 12 00:24:15.152695 systemd[2082]: Startup finished in 201ms. Jul 12 00:24:15.152806 systemd[1]: Started user@500.service. Jul 12 00:24:15.157412 systemd[1]: Started session-1.scope. Jul 12 00:24:15.229705 etcd-wrapper[2038]: 7a57323f7551: Verifying Checksum Jul 12 00:24:15.229996 etcd-wrapper[2038]: 7a57323f7551: Download complete Jul 12 00:24:15.304939 etcd-wrapper[2038]: d462aa345367: Pull complete Jul 12 00:24:15.315928 systemd[1]: Started sshd@1-172.31.18.187:22-147.75.109.163:40454.service. Jul 12 00:24:15.419372 systemd[1]: var-lib-docker-overlay2-2c0519226452f9280a48e5f2ed101aa9d57c61c301597a27146fcf6cfb0c5a1d-merged.mount: Deactivated successfully. Jul 12 00:24:15.450008 etcd-wrapper[2038]: 0f8b424aa0b9: Pull complete Jul 12 00:24:15.496766 sshd[2091]: Accepted publickey for core from 147.75.109.163 port 40454 ssh2: RSA SHA256:hAayEOBHnTpwll2xPQSU8cSp7XCWn/pXChvPbqogNKA Jul 12 00:24:15.499388 sshd[2091]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 12 00:24:15.508022 systemd[1]: Started session-2.scope. Jul 12 00:24:15.510405 systemd-logind[1715]: New session 2 of user core. Jul 12 00:24:15.560560 systemd[1]: var-lib-docker-overlay2-7dd26d8b8a83955c17a3fa2823156713535ce2cc6697b7ba423a20a3e254448d-merged.mount: Deactivated successfully. Jul 12 00:24:15.593240 etcd-wrapper[2038]: d557676654e5: Pull complete Jul 12 00:24:15.643829 sshd[2091]: pam_unix(sshd:session): session closed for user core Jul 12 00:24:15.649250 systemd[1]: session-2.scope: Deactivated successfully. Jul 12 00:24:15.650361 systemd[1]: sshd@1-172.31.18.187:22-147.75.109.163:40454.service: Deactivated successfully. Jul 12 00:24:15.652643 systemd-logind[1715]: Session 2 logged out. Waiting for processes to exit. Jul 12 00:24:15.654260 systemd-logind[1715]: Removed session 2. Jul 12 00:24:15.671631 systemd[1]: Started sshd@2-172.31.18.187:22-147.75.109.163:40468.service. Jul 12 00:24:15.730025 etcd-wrapper[2038]: c8022d07192e: Pull complete Jul 12 00:24:15.842472 etcd-wrapper[2038]: d858cbc252ad: Pull complete Jul 12 00:24:15.849318 sshd[2115]: Accepted publickey for core from 147.75.109.163 port 40468 ssh2: RSA SHA256:hAayEOBHnTpwll2xPQSU8cSp7XCWn/pXChvPbqogNKA Jul 12 00:24:15.851797 sshd[2115]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 12 00:24:15.860434 systemd[1]: Started session-3.scope. Jul 12 00:24:15.863102 systemd-logind[1715]: New session 3 of user core. Jul 12 00:24:15.972998 etcd-wrapper[2038]: 1069fc2daed1: Pull complete Jul 12 00:24:15.990897 sshd[2115]: pam_unix(sshd:session): session closed for user core Jul 12 00:24:15.996111 systemd[1]: session-3.scope: Deactivated successfully. Jul 12 00:24:15.997255 systemd[1]: sshd@2-172.31.18.187:22-147.75.109.163:40468.service: Deactivated successfully. Jul 12 00:24:15.999799 systemd-logind[1715]: Session 3 logged out. Waiting for processes to exit. Jul 12 00:24:16.001914 systemd-logind[1715]: Removed session 3. Jul 12 00:24:16.088225 etcd-wrapper[2038]: b40161cd83fc: Pull complete Jul 12 00:24:16.180164 systemd[1]: var-lib-docker-overlay2-24b603e04db3989227a56d787d133e442dee5867eca786cbc4fc2fb89e801f02-merged.mount: Deactivated successfully. Jul 12 00:24:16.201570 etcd-wrapper[2038]: 5318d93a3a65: Pull complete Jul 12 00:24:16.296289 systemd[1]: var-lib-docker-overlay2-e343ff9553b53f9462d6bf1d3809d1df5d6e36ca34d52f48fcf56cdab833912d-merged.mount: Deactivated successfully. Jul 12 00:24:16.322511 etcd-wrapper[2038]: 307c1adadb60: Pull complete Jul 12 00:24:16.871008 etcd-wrapper[2038]: f9b3c65df792: Pull complete Jul 12 00:24:17.144588 systemd[1]: var-lib-docker-overlay2-aab60c22f582a438cf2d76f68f98b182b5ffa1ca0f654f1b92ab669a96306237-merged.mount: Deactivated successfully. Jul 12 00:24:17.269463 systemd[1]: var-lib-docker-overlay2-54f622b4bb45498d6e95022da490d89aa1a0ad3147baeb4cb4fd167a2d0fb6f9-merged.mount: Deactivated successfully. Jul 12 00:24:17.307832 etcd-wrapper[2038]: 5709b4a82c35: Pull complete Jul 12 00:24:17.661318 systemd[1]: var-lib-docker-overlay2-3b68fb099b7078325d34c547b8ee7a220b7c7789ccc2205a5247f7f7276a2566-merged.mount: Deactivated successfully. Jul 12 00:24:17.703410 etcd-wrapper[2038]: d5372e382b07: Pull complete Jul 12 00:24:17.803762 etcd-wrapper[2038]: 9c3b5253dddc: Pull complete Jul 12 00:24:17.903561 etcd-wrapper[2038]: 7a57323f7551: Pull complete Jul 12 00:24:17.913503 etcd-wrapper[2038]: Digest: sha256:d967d98a12dc220a1a290794711dba7eba04b8ce465e12b02383d1bfbb33e159 Jul 12 00:24:17.916316 etcd-wrapper[2038]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.16 Jul 12 00:24:18.090456 env[1721]: time="2025-07-12T00:24:18.089494374Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jul 12 00:24:18.090456 env[1721]: time="2025-07-12T00:24:18.090005132Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jul 12 00:24:18.090456 env[1721]: time="2025-07-12T00:24:18.090036272Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 12 00:24:18.091305 env[1721]: time="2025-07-12T00:24:18.090584643Z" level=info msg="starting signal loop" namespace=moby path=/run/containerd/io.containerd.runtime.v2.task/moby/c51442f56ac6ab48a9e213ef721945f982c250ac0a1fb19d0d749bfb48b86aa5 pid=2194 runtime=io.containerd.runc.v2 Jul 12 00:24:18.111180 systemd[1]: Started docker-c51442f56ac6ab48a9e213ef721945f982c250ac0a1fb19d0d749bfb48b86aa5.scope. Jul 12 00:24:18.145461 systemd[1]: var-lib-docker-overlay2-623a052240fecc8be830c0ea3a586079264650fcedc5a2194dc263bdb16ebb8a-merged.mount: Deactivated successfully. Jul 12 00:24:18.234149 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.233084Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} Jul 12 00:24:18.234432 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.233833Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"ec2d07447f3aa9ac1a8ad7e3c5f524cd"} Jul 12 00:24:18.234646 etcd-wrapper[2038]: {"level":"warn","ts":"2025-07-12T00:24:18.234455Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} Jul 12 00:24:18.234907 etcd-wrapper[2038]: {"level":"warn","ts":"2025-07-12T00:24:18.234700Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.16"} Jul 12 00:24:18.235136 etcd-wrapper[2038]: {"level":"warn","ts":"2025-07-12T00:24:18.234915Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} Jul 12 00:24:18.235405 etcd-wrapper[2038]: {"level":"warn","ts":"2025-07-12T00:24:18.235189Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} Jul 12 00:24:18.235742 etcd-wrapper[2038]: {"level":"warn","ts":"2025-07-12T00:24:18.235476Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Jul 12 00:24:18.236035 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.235779Z","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.18.187:2379"]} Jul 12 00:24:18.236399 etcd-wrapper[2038]: {"level":"warn","ts":"2025-07-12T00:24:18.236150Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Jul 12 00:24:18.236694 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.236465Z","caller":"embed/etcd.go:128","msg":"configuring peer listeners","listen-peer-urls":["http://localhost:2380"]} Jul 12 00:24:18.237907 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.237497Z","caller":"embed/etcd.go:136","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} Jul 12 00:24:18.238448 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.238123Z","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":"ec2d07447f3aa9ac1a8ad7e3c5f524cd","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.18.187:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"ec2d07447f3aa9ac1a8ad7e3c5f524cd=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"} Jul 12 00:24:18.240995 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.240583Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"1.660825ms"} Jul 12 00:24:18.245004 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.244667Z","caller":"etcdserver/raft.go:505","msg":"starting local member","local-member-id":"8e9e05c52164694d","cluster-id":"cdf818194e3a8c32"} Jul 12 00:24:18.245535 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.245285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=()"} Jul 12 00:24:18.245790 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.245532Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 0"} Jul 12 00:24:18.246002 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.245780Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8e9e05c52164694d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} Jul 12 00:24:18.246313 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.245964Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 1"} Jul 12 00:24:18.246313 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.246099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Jul 12 00:24:18.249428 etcd-wrapper[2038]: {"level":"warn","ts":"2025-07-12T00:24:18.249079Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} Jul 12 00:24:18.250407 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.250120Z","caller":"mvcc/kvstore.go:423","msg":"kvstore restored","current-rev":1} Jul 12 00:24:18.251291 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.251039Z","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"} Jul 12 00:24:18.252150 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.251896Z","caller":"etcdserver/server.go:873","msg":"starting etcd server","local-member-id":"8e9e05c52164694d","local-server-version":"3.5.16","cluster-version":"to_be_decided"} Jul 12 00:24:18.252922 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.252594Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Jul 12 00:24:18.254849 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.254332Z","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"} Jul 12 00:24:18.256117 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.255776Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} Jul 12 00:24:18.256117 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.255843Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} Jul 12 00:24:18.256117 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.255864Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} Jul 12 00:24:18.257058 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.256754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Jul 12 00:24:18.257573 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.257320Z","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"]} Jul 12 00:24:18.258512 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.258101Z","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.18.187:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} Jul 12 00:24:18.258512 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.258121Z","caller":"embed/etcd.go:600","msg":"serving peer traffic","address":"127.0.0.1:2380"} Jul 12 00:24:18.258512 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.258327Z","caller":"embed/etcd.go:572","msg":"cmux::serve","address":"127.0.0.1:2380"} Jul 12 00:24:18.447960 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.447614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d is starting a new election at term 1"} Jul 12 00:24:18.447960 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.447663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became pre-candidate at term 1"} Jul 12 00:24:18.448536 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.448218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgPreVoteResp from 8e9e05c52164694d at term 1"} Jul 12 00:24:18.448536 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.448266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became candidate at term 2"} Jul 12 00:24:18.448536 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.448287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 2"} Jul 12 00:24:18.448536 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.448308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became leader at term 2"} Jul 12 00:24:18.448536 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.448336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 2"} Jul 12 00:24:18.449914 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.449613Z","caller":"etcdserver/server.go:2651","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} Jul 12 00:24:18.450609 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.450290Z","caller":"etcdserver/server.go:2140","msg":"published local member to cluster through raft","local-member-id":"8e9e05c52164694d","local-member-attributes":"{Name:ec2d07447f3aa9ac1a8ad7e3c5f524cd ClientURLs:[http://172.31.18.187:2379]}","request-path":"/0/members/8e9e05c52164694d/attributes","cluster-id":"cdf818194e3a8c32","publish-timeout":"7s"} Jul 12 00:24:18.450609 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.450341Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} Jul 12 00:24:18.451274 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.450963Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","cluster-version":"3.5"} Jul 12 00:24:18.452137 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.451833Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} Jul 12 00:24:18.452288 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.451902Z","caller":"etcdserver/server.go:2675","msg":"cluster version is updated","cluster-version":"3.5"} Jul 12 00:24:18.452613 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.451855Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Jul 12 00:24:18.453185 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.452909Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} Jul 12 00:24:18.453627 systemd[1]: Started etcd-member.service. Jul 12 00:24:18.463365 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.453263Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} Jul 12 00:24:18.455850 systemd[1]: Reached target multi-user.target. Jul 12 00:24:18.460525 systemd[1]: Starting systemd-update-utmp-runlevel.service... Jul 12 00:24:18.464912 etcd-wrapper[2038]: {"level":"info","ts":"2025-07-12T00:24:18.457856Z","caller":"embed/serve.go:187","msg":"serving client traffic insecurely; this is strongly discouraged!","traffic":"grpc+http","address":"[::]:2379"} Jul 12 00:24:18.476329 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Jul 12 00:24:18.476763 systemd[1]: Finished systemd-update-utmp-runlevel.service. Jul 12 00:24:18.479161 systemd[1]: Startup finished in 1.196s (kernel) + 6.322s (initrd) + 15.554s (userspace) = 23.074s. Jul 12 00:24:21.316991 amazon-ssm-agent[1722]: 2025-07-12 00:24:21 INFO [MessagingDeliveryService] [Association] No associations on boot. Requerying for associations after 30 seconds. Jul 12 00:24:25.939274 systemd[1]: Started sshd@3-172.31.18.187:22-147.75.109.163:33510.service. Jul 12 00:24:26.114686 sshd[2235]: Accepted publickey for core from 147.75.109.163 port 33510 ssh2: RSA SHA256:hAayEOBHnTpwll2xPQSU8cSp7XCWn/pXChvPbqogNKA Jul 12 00:24:26.117743 sshd[2235]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 12 00:24:26.125855 systemd[1]: Started session-4.scope. Jul 12 00:24:26.126834 systemd-logind[1715]: New session 4 of user core. Jul 12 00:24:26.256257 sshd[2235]: pam_unix(sshd:session): session closed for user core Jul 12 00:24:26.261890 systemd-logind[1715]: Session 4 logged out. Waiting for processes to exit. Jul 12 00:24:26.262434 systemd[1]: sshd@3-172.31.18.187:22-147.75.109.163:33510.service: Deactivated successfully. Jul 12 00:24:26.263636 systemd[1]: session-4.scope: Deactivated successfully. Jul 12 00:24:26.265170 systemd-logind[1715]: Removed session 4. Jul 12 00:24:26.283830 systemd[1]: Started sshd@4-172.31.18.187:22-147.75.109.163:52516.service. Jul 12 00:24:26.458758 sshd[2241]: Accepted publickey for core from 147.75.109.163 port 52516 ssh2: RSA SHA256:hAayEOBHnTpwll2xPQSU8cSp7XCWn/pXChvPbqogNKA Jul 12 00:24:26.461222 sshd[2241]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 12 00:24:26.469683 systemd[1]: Started session-5.scope. Jul 12 00:24:26.472001 systemd-logind[1715]: New session 5 of user core. Jul 12 00:24:26.592540 sshd[2241]: pam_unix(sshd:session): session closed for user core Jul 12 00:24:26.598184 systemd-logind[1715]: Session 5 logged out. Waiting for processes to exit. Jul 12 00:24:26.598823 systemd[1]: sshd@4-172.31.18.187:22-147.75.109.163:52516.service: Deactivated successfully. Jul 12 00:24:26.600106 systemd[1]: session-5.scope: Deactivated successfully. Jul 12 00:24:26.601677 systemd-logind[1715]: Removed session 5. Jul 12 00:24:26.619849 systemd[1]: Started sshd@5-172.31.18.187:22-147.75.109.163:52518.service. Jul 12 00:24:26.790325 sshd[2247]: Accepted publickey for core from 147.75.109.163 port 52518 ssh2: RSA SHA256:hAayEOBHnTpwll2xPQSU8cSp7XCWn/pXChvPbqogNKA Jul 12 00:24:26.792900 sshd[2247]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 12 00:24:26.801346 systemd[1]: Started session-6.scope. Jul 12 00:24:26.802140 systemd-logind[1715]: New session 6 of user core. Jul 12 00:24:26.930795 sshd[2247]: pam_unix(sshd:session): session closed for user core Jul 12 00:24:26.935631 systemd[1]: session-6.scope: Deactivated successfully. Jul 12 00:24:26.936801 systemd[1]: sshd@5-172.31.18.187:22-147.75.109.163:52518.service: Deactivated successfully. Jul 12 00:24:26.938335 systemd-logind[1715]: Session 6 logged out. Waiting for processes to exit. Jul 12 00:24:26.940264 systemd-logind[1715]: Removed session 6. Jul 12 00:24:26.958991 systemd[1]: Started sshd@6-172.31.18.187:22-147.75.109.163:52532.service. Jul 12 00:24:27.132575 sshd[2253]: Accepted publickey for core from 147.75.109.163 port 52532 ssh2: RSA SHA256:hAayEOBHnTpwll2xPQSU8cSp7XCWn/pXChvPbqogNKA Jul 12 00:24:27.135517 sshd[2253]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 12 00:24:27.143637 systemd[1]: Started session-7.scope. Jul 12 00:24:27.145801 systemd-logind[1715]: New session 7 of user core. Jul 12 00:24:27.268671 sudo[2256]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Jul 12 00:24:27.269804 sudo[2256]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Jul 12 00:24:27.288955 dbus-daemon[1705]: avc: received setenforce notice (enforcing=1) Jul 12 00:24:27.292002 sudo[2256]: pam_unix(sudo:session): session closed for user root Jul 12 00:24:27.317121 sshd[2253]: pam_unix(sshd:session): session closed for user core Jul 12 00:24:27.322421 systemd-logind[1715]: Session 7 logged out. Waiting for processes to exit. Jul 12 00:24:27.323086 systemd[1]: sshd@6-172.31.18.187:22-147.75.109.163:52532.service: Deactivated successfully. Jul 12 00:24:27.324378 systemd[1]: session-7.scope: Deactivated successfully. Jul 12 00:24:27.325939 systemd-logind[1715]: Removed session 7. Jul 12 00:24:27.345449 systemd[1]: Started sshd@7-172.31.18.187:22-147.75.109.163:52540.service. Jul 12 00:24:27.522608 sshd[2260]: Accepted publickey for core from 147.75.109.163 port 52540 ssh2: RSA SHA256:hAayEOBHnTpwll2xPQSU8cSp7XCWn/pXChvPbqogNKA Jul 12 00:24:27.525656 sshd[2260]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 12 00:24:27.533932 systemd-logind[1715]: New session 8 of user core. Jul 12 00:24:27.534282 systemd[1]: Started session-8.scope. Jul 12 00:24:27.642045 sudo[2264]: 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 Jul 12 00:24:27.642540 sudo[2264]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Jul 12 00:24:27.647694 sudo[2264]: pam_unix(sudo:session): session closed for user root Jul 12 00:24:27.656599 sudo[2263]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/systemctl restart audit-rules Jul 12 00:24:27.657628 sudo[2263]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Jul 12 00:24:27.675417 systemd[1]: Stopping audit-rules.service... Jul 12 00:24:27.675000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Jul 12 00:24:27.679304 kernel: kauditd_printk_skb: 67 callbacks suppressed Jul 12 00:24:27.679385 kernel: audit: type=1305 audit(1752279867.675:161): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Jul 12 00:24:27.679603 auditctl[2267]: No rules Jul 12 00:24:27.685121 systemd[1]: audit-rules.service: Deactivated successfully. Jul 12 00:24:27.675000 audit[2267]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffdec571f0 a2=420 a3=0 items=0 ppid=1 pid=2267 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) Jul 12 00:24:27.696702 kernel: audit: type=1300 audit(1752279867.675:161): arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffdec571f0 a2=420 a3=0 items=0 ppid=1 pid=2267 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) Jul 12 00:24:27.685527 systemd[1]: Stopped audit-rules.service. Jul 12 00:24:27.693200 systemd[1]: Starting audit-rules.service... Jul 12 00:24:27.675000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D44 Jul 12 00:24:27.701851 kernel: audit: type=1327 audit(1752279867.675:161): proctitle=2F7362696E2F617564697463746C002D44 Jul 12 00:24:27.701955 kernel: audit: type=1131 audit(1752279867.684:162): 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' Jul 12 00:24:27.684000 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' Jul 12 00:24:27.728979 augenrules[2284]: No rules Jul 12 00:24:27.730000 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' Jul 12 00:24:27.730519 systemd[1]: Finished audit-rules.service. Jul 12 00:24:27.734043 sudo[2263]: pam_unix(sudo:session): session closed for user root Jul 12 00:24:27.733000 audit[2263]: USER_END pid=2263 uid=500 auid=500 ses=8 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' Jul 12 00:24:27.749138 kernel: audit: type=1130 audit(1752279867.730:163): 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' Jul 12 00:24:27.749211 kernel: audit: type=1106 audit(1752279867.733:164): pid=2263 uid=500 auid=500 ses=8 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' Jul 12 00:24:27.749269 kernel: audit: type=1104 audit(1752279867.733:165): pid=2263 uid=500 auid=500 ses=8 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' Jul 12 00:24:27.733000 audit[2263]: CRED_DISP pid=2263 uid=500 auid=500 ses=8 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' Jul 12 00:24:27.762176 sshd[2260]: pam_unix(sshd:session): session closed for user core Jul 12 00:24:27.763000 audit[2260]: USER_END pid=2260 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=147.75.109.163 addr=147.75.109.163 terminal=ssh res=success' Jul 12 00:24:27.766524 systemd[1]: session-8.scope: Deactivated successfully. Jul 12 00:24:27.767649 systemd[1]: sshd@7-172.31.18.187:22-147.75.109.163:52540.service: Deactivated successfully. Jul 12 00:24:27.770754 systemd-logind[1715]: Session 8 logged out. Waiting for processes to exit. Jul 12 00:24:27.772384 systemd-logind[1715]: Removed session 8. Jul 12 00:24:27.763000 audit[2260]: CRED_DISP pid=2260 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=147.75.109.163 addr=147.75.109.163 terminal=ssh res=success' Jul 12 00:24:27.785484 kernel: audit: type=1106 audit(1752279867.763:166): pid=2260 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=147.75.109.163 addr=147.75.109.163 terminal=ssh res=success' Jul 12 00:24:27.785600 kernel: audit: type=1104 audit(1752279867.763:167): pid=2260 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=147.75.109.163 addr=147.75.109.163 terminal=ssh res=success' Jul 12 00:24:27.767000 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.18.187:22-147.75.109.163:52540 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 12 00:24:27.795495 kernel: audit: type=1131 audit(1752279867.767:168): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@7-172.31.18.187:22-147.75.109.163:52540 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 12 00:24:27.801862 systemd[1]: Started sshd@8-172.31.18.187:22-147.75.109.163:52548.service. Jul 12 00:24:27.801000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@8-172.31.18.187:22-147.75.109.163:52548 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 12 00:24:27.976000 audit[2290]: USER_ACCT pid=2290 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=147.75.109.163 addr=147.75.109.163 terminal=ssh res=success' Jul 12 00:24:27.978393 sshd[2290]: Accepted publickey for core from 147.75.109.163 port 52548 ssh2: RSA SHA256:hAayEOBHnTpwll2xPQSU8cSp7XCWn/pXChvPbqogNKA Jul 12 00:24:27.978000 audit[2290]: CRED_ACQ pid=2290 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=147.75.109.163 addr=147.75.109.163 terminal=ssh res=success' Jul 12 00:24:27.978000 audit[2290]: SYSCALL arch=c00000b7 syscall=64 success=yes exit=3 a0=5 a1=fffff45b6430 a2=3 a3=1 items=0 ppid=1 pid=2290 auid=500 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=9 comm="sshd" exe="/usr/sbin/sshd" subj=system_u:system_r:kernel_t:s0 key=(null) Jul 12 00:24:27.978000 audit: PROCTITLE proctitle=737368643A20636F7265205B707269765D Jul 12 00:24:27.981322 sshd[2290]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 12 00:24:27.989879 systemd[1]: Started session-9.scope. Jul 12 00:24:27.991073 systemd-logind[1715]: New session 9 of user core. Jul 12 00:24:27.998000 audit[2290]: USER_START pid=2290 uid=0 auid=500 ses=9 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=147.75.109.163 addr=147.75.109.163 terminal=ssh res=success' Jul 12 00:24:28.000000 audit[2292]: CRED_ACQ pid=2292 uid=0 auid=500 ses=9 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=147.75.109.163 addr=147.75.109.163 terminal=ssh res=success' Jul 12 00:24:28.114684 sshd[2290]: pam_unix(sshd:session): session closed for user core Jul 12 00:24:28.116000 audit[2290]: USER_END pid=2290 uid=0 auid=500 ses=9 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=147.75.109.163 addr=147.75.109.163 terminal=ssh res=success' Jul 12 00:24:28.116000 audit[2290]: CRED_DISP pid=2290 uid=0 auid=500 ses=9 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=147.75.109.163 addr=147.75.109.163 terminal=ssh res=success' Jul 12 00:24:28.119850 systemd[1]: sshd@8-172.31.18.187:22-147.75.109.163:52548.service: Deactivated successfully. Jul 12 00:24:28.119000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@8-172.31.18.187:22-147.75.109.163:52548 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 12 00:24:28.121103 systemd[1]: session-9.scope: Deactivated successfully. Jul 12 00:24:28.122301 systemd-logind[1715]: Session 9 logged out. Waiting for processes to exit. Jul 12 00:24:28.124242 systemd-logind[1715]: Removed session 9. Jul 12 00:24:28.141448 systemd[1]: Started sshd@9-172.31.18.187:22-147.75.109.163:52564.service. Jul 12 00:24:28.141000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@9-172.31.18.187:22-147.75.109.163:52564 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 12 00:24:28.311000 audit[2298]: USER_ACCT pid=2298 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=147.75.109.163 addr=147.75.109.163 terminal=ssh res=success' Jul 12 00:24:28.313150 sshd[2298]: Accepted publickey for core from 147.75.109.163 port 52564 ssh2: RSA SHA256:hAayEOBHnTpwll2xPQSU8cSp7XCWn/pXChvPbqogNKA Jul 12 00:24:28.313000 audit[2298]: CRED_ACQ pid=2298 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=147.75.109.163 addr=147.75.109.163 terminal=ssh res=success' Jul 12 00:24:28.313000 audit[2298]: SYSCALL arch=c00000b7 syscall=64 success=yes exit=3 a0=5 a1=ffffcfe48550 a2=3 a3=1 items=0 ppid=1 pid=2298 auid=500 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=10 comm="sshd" exe="/usr/sbin/sshd" subj=system_u:system_r:kernel_t:s0 key=(null) Jul 12 00:24:28.313000 audit: PROCTITLE proctitle=737368643A20636F7265205B707269765D Jul 12 00:24:28.316037 sshd[2298]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 12 00:24:28.323954 systemd-logind[1715]: New session 10 of user core. Jul 12 00:24:28.324880 systemd[1]: Started session-10.scope. Jul 12 00:24:28.332000 audit[2298]: USER_START pid=2298 uid=0 auid=500 ses=10 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=147.75.109.163 addr=147.75.109.163 terminal=ssh res=success' Jul 12 00:24:28.334000 audit[2300]: CRED_ACQ pid=2300 uid=0 auid=500 ses=10 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=147.75.109.163 addr=147.75.109.163 terminal=ssh res=success' Jul 12 00:24:28.629480 sshd[2298]: pam_unix(sshd:session): session closed for user core Jul 12 00:24:28.631000 audit[2298]: USER_END pid=2298 uid=0 auid=500 ses=10 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=147.75.109.163 addr=147.75.109.163 terminal=ssh res=success' Jul 12 00:24:28.631000 audit[2298]: CRED_DISP pid=2298 uid=0 auid=500 ses=10 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=147.75.109.163 addr=147.75.109.163 terminal=ssh res=success' Jul 12 00:24:28.634268 systemd[1]: session-10.scope: Deactivated successfully. Jul 12 00:24:28.635394 systemd[1]: sshd@9-172.31.18.187:22-147.75.109.163:52564.service: Deactivated successfully. Jul 12 00:24:28.635000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@9-172.31.18.187:22-147.75.109.163:52564 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 12 00:24:28.636986 systemd-logind[1715]: Session 10 logged out. Waiting for processes to exit. Jul 12 00:24:28.638574 systemd-logind[1715]: Removed session 10. Jul 12 00:24:40.699993 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Jul 12 00:24:40.698000 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' Jul 12 00:24:40.702481 kernel: kauditd_printk_skb: 22 callbacks suppressed Jul 12 00:24:40.702564 kernel: audit: type=1131 audit(1752279880.698:187): 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' Jul 12 00:24:40.734000 audit: BPF prog-id=31 op=UNLOAD Jul 12 00:24:40.734000 audit: BPF prog-id=30 op=UNLOAD Jul 12 00:24:40.741151 kernel: audit: type=1334 audit(1752279880.734:188): prog-id=31 op=UNLOAD Jul 12 00:24:40.741210 kernel: audit: type=1334 audit(1752279880.734:189): prog-id=30 op=UNLOAD Jul 12 00:24:40.741252 kernel: audit: type=1334 audit(1752279880.734:190): prog-id=29 op=UNLOAD Jul 12 00:24:40.734000 audit: BPF prog-id=29 op=UNLOAD Jul 12 00:24:51.352370 amazon-ssm-agent[1722]: 2025-07-12 00:24:51 INFO [MessagingDeliveryService] [Association] Schedule manager refreshed with 0 associations, 0 new associations associated Jul 12 00:24:55.420644 update_engine[1716]: I0712 00:24:55.420104 1716 update_attempter.cc:509] Updating boot flags... Jul 12 00:25:27.889107 amazon-ssm-agent[1722]: 2025-07-12 00:25:27 INFO [HealthCheck] HealthCheck reporting agent health.