Dec 13 14:12:57.953379 kernel: Booting Linux on physical CPU 0x0000000000 [0x410fd083] Dec 13 14:12:57.953416 kernel: Linux version 5.15.173-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 11.3.1_p20221209 p3) 11.3.1 20221209, GNU ld (Gentoo 2.39 p5) 2.39.0) #1 SMP PREEMPT Fri Dec 13 12:58:58 -00 2024 Dec 13 14:12:57.953440 kernel: efi: EFI v2.70 by EDK II Dec 13 14:12:57.953456 kernel: efi: SMBIOS=0x7bed0000 SMBIOS 3.0=0x7beb0000 ACPI=0x786e0000 ACPI 2.0=0x786e0014 MEMATTR=0x7b003a98 MEMRESERVE=0x7171cf98 Dec 13 14:12:57.953470 kernel: ACPI: Early table checksum verification disabled Dec 13 14:12:57.953483 kernel: ACPI: RSDP 0x00000000786E0014 000024 (v02 AMAZON) Dec 13 14:12:57.953499 kernel: ACPI: XSDT 0x00000000786D00E8 000064 (v01 AMAZON AMZNFACP 00000001 01000013) Dec 13 14:12:57.953514 kernel: ACPI: FACP 0x00000000786B0000 000114 (v06 AMAZON AMZNFACP 00000001 AMZN 00000001) Dec 13 14:12:57.953527 kernel: ACPI: DSDT 0x0000000078640000 00159D (v02 AMAZON AMZNDSDT 00000001 INTL 20160527) Dec 13 14:12:57.953541 kernel: ACPI: APIC 0x00000000786C0000 000108 (v04 AMAZON AMZNAPIC 00000001 AMZN 00000001) Dec 13 14:12:57.953559 kernel: ACPI: SPCR 0x00000000786A0000 000050 (v02 AMAZON AMZNSPCR 00000001 AMZN 00000001) Dec 13 14:12:57.953578 kernel: ACPI: GTDT 0x0000000078690000 000060 (v02 AMAZON AMZNGTDT 00000001 AMZN 00000001) Dec 13 14:12:57.953595 kernel: ACPI: MCFG 0x0000000078680000 00003C (v02 AMAZON AMZNMCFG 00000001 AMZN 00000001) Dec 13 14:12:57.953609 kernel: ACPI: SLIT 0x0000000078670000 00002D (v01 AMAZON AMZNSLIT 00000001 AMZN 00000001) Dec 13 14:12:57.953625 kernel: ACPI: IORT 0x0000000078660000 000078 (v01 AMAZON AMZNIORT 00000001 AMZN 00000001) Dec 13 14:12:57.953645 kernel: ACPI: PPTT 0x0000000078650000 0000EC (v01 AMAZON AMZNPPTT 00000001 AMZN 00000001) Dec 13 14:12:57.953660 kernel: ACPI: SPCR: console: uart,mmio,0x90a0000,115200 Dec 13 14:12:57.953674 kernel: earlycon: uart0 at MMIO 0x00000000090a0000 (options '115200') Dec 13 14:12:57.953689 kernel: printk: bootconsole [uart0] enabled Dec 13 14:12:57.953704 kernel: NUMA: Failed to initialise from firmware Dec 13 14:12:57.953719 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000004b5ffffff] Dec 13 14:12:57.953733 kernel: NUMA: NODE_DATA [mem 0x4b5843900-0x4b5848fff] Dec 13 14:12:57.953749 kernel: Zone ranges: Dec 13 14:12:57.953783 kernel: DMA [mem 0x0000000040000000-0x00000000ffffffff] Dec 13 14:12:57.953810 kernel: DMA32 empty Dec 13 14:12:57.953840 kernel: Normal [mem 0x0000000100000000-0x00000004b5ffffff] Dec 13 14:12:57.953892 kernel: Movable zone start for each node Dec 13 14:12:57.953945 kernel: Early memory node ranges Dec 13 14:12:57.953961 kernel: node 0: [mem 0x0000000040000000-0x000000007862ffff] Dec 13 14:12:57.953976 kernel: node 0: [mem 0x0000000078630000-0x000000007863ffff] Dec 13 14:12:57.953991 kernel: node 0: [mem 0x0000000078640000-0x00000000786effff] Dec 13 14:12:57.954006 kernel: node 0: [mem 0x00000000786f0000-0x000000007872ffff] Dec 13 14:12:57.954021 kernel: node 0: [mem 0x0000000078730000-0x000000007bbfffff] Dec 13 14:12:57.954035 kernel: node 0: [mem 0x000000007bc00000-0x000000007bfdffff] Dec 13 14:12:57.954050 kernel: node 0: [mem 0x000000007bfe0000-0x000000007fffffff] Dec 13 14:12:57.955549 kernel: node 0: [mem 0x0000000400000000-0x00000004b5ffffff] Dec 13 14:12:57.955568 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000004b5ffffff] Dec 13 14:12:57.955583 kernel: On node 0, zone Normal: 8192 pages in unavailable ranges Dec 13 14:12:57.955606 kernel: psci: probing for conduit method from ACPI. Dec 13 14:12:57.955621 kernel: psci: PSCIv1.0 detected in firmware. Dec 13 14:12:57.955642 kernel: psci: Using standard PSCI v0.2 function IDs Dec 13 14:12:57.955658 kernel: psci: Trusted OS migration not required Dec 13 14:12:57.955673 kernel: psci: SMC Calling Convention v1.1 Dec 13 14:12:57.955693 kernel: ACPI: SRAT not present Dec 13 14:12:57.955709 kernel: percpu: Embedded 30 pages/cpu s83032 r8192 d31656 u122880 Dec 13 14:12:57.955724 kernel: pcpu-alloc: s83032 r8192 d31656 u122880 alloc=30*4096 Dec 13 14:12:57.955740 kernel: pcpu-alloc: [0] 0 [0] 1 Dec 13 14:12:57.955755 kernel: Detected PIPT I-cache on CPU0 Dec 13 14:12:57.955770 kernel: CPU features: detected: GIC system register CPU interface Dec 13 14:12:57.955785 kernel: CPU features: detected: Spectre-v2 Dec 13 14:12:57.955800 kernel: CPU features: detected: Spectre-v3a Dec 13 14:12:57.955815 kernel: CPU features: detected: Spectre-BHB Dec 13 14:12:57.955830 kernel: CPU features: kernel page table isolation forced ON by KASLR Dec 13 14:12:57.955845 kernel: CPU features: detected: Kernel page table isolation (KPTI) Dec 13 14:12:57.955885 kernel: CPU features: detected: ARM erratum 1742098 Dec 13 14:12:57.955906 kernel: CPU features: detected: ARM errata 1165522, 1319367, or 1530923 Dec 13 14:12:57.955921 kernel: Built 1 zonelists, mobility grouping on. Total pages: 991872 Dec 13 14:12:57.955936 kernel: Policy zone: Normal Dec 13 14:12:57.955954 kernel: Kernel command line: BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlycon flatcar.first_boot=detected acpi=force flatcar.oem.id=ec2 modprobe.blacklist=xen_fbfront net.ifnames=0 nvme_core.io_timeout=4294967295 verity.usrhash=5997a8cf94b1df1856dc785f0a7074604bbf4c21fdcca24a1996021471a77601 Dec 13 14:12:57.955971 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Dec 13 14:12:57.955986 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Dec 13 14:12:57.956001 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Dec 13 14:12:57.956016 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Dec 13 14:12:57.956031 kernel: software IO TLB: mapped [mem 0x000000007c000000-0x0000000080000000] (64MB) Dec 13 14:12:57.956053 kernel: Memory: 3824524K/4030464K available (9792K kernel code, 2092K rwdata, 7576K rodata, 36416K init, 777K bss, 205940K reserved, 0K cma-reserved) Dec 13 14:12:57.956069 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Dec 13 14:12:57.956084 kernel: trace event string verifier disabled Dec 13 14:12:57.956099 kernel: rcu: Preemptible hierarchical RCU implementation. Dec 13 14:12:57.956115 kernel: rcu: RCU event tracing is enabled. Dec 13 14:12:57.956131 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Dec 13 14:12:57.956147 kernel: Trampoline variant of Tasks RCU enabled. Dec 13 14:12:57.956162 kernel: Tracing variant of Tasks RCU enabled. Dec 13 14:12:57.956177 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Dec 13 14:12:57.956193 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Dec 13 14:12:57.956208 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Dec 13 14:12:57.956223 kernel: GICv3: 96 SPIs implemented Dec 13 14:12:57.956242 kernel: GICv3: 0 Extended SPIs implemented Dec 13 14:12:57.956257 kernel: GICv3: Distributor has no Range Selector support Dec 13 14:12:57.956272 kernel: Root IRQ handler: gic_handle_irq Dec 13 14:12:57.956287 kernel: GICv3: 16 PPIs implemented Dec 13 14:12:57.956302 kernel: GICv3: CPU0: found redistributor 0 region 0:0x0000000010200000 Dec 13 14:12:57.956317 kernel: ACPI: SRAT not present Dec 13 14:12:57.956332 kernel: ITS [mem 0x10080000-0x1009ffff] Dec 13 14:12:57.956347 kernel: ITS@0x0000000010080000: allocated 8192 Devices @400090000 (indirect, esz 8, psz 64K, shr 1) Dec 13 14:12:57.956363 kernel: ITS@0x0000000010080000: allocated 8192 Interrupt Collections @4000a0000 (flat, esz 8, psz 64K, shr 1) Dec 13 14:12:57.956378 kernel: GICv3: using LPI property table @0x00000004000b0000 Dec 13 14:12:57.956393 kernel: ITS: Using hypervisor restricted LPI range [128] Dec 13 14:12:57.956412 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000004000d0000 Dec 13 14:12:57.956428 kernel: arch_timer: cp15 timer(s) running at 83.33MHz (virt). Dec 13 14:12:57.956443 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x13381ebeec, max_idle_ns: 440795203145 ns Dec 13 14:12:57.956459 kernel: sched_clock: 56 bits at 83MHz, resolution 12ns, wraps every 4398046511100ns Dec 13 14:12:57.956474 kernel: Console: colour dummy device 80x25 Dec 13 14:12:57.956490 kernel: printk: console [tty1] enabled Dec 13 14:12:57.956506 kernel: ACPI: Core revision 20210730 Dec 13 14:12:57.956522 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 166.66 BogoMIPS (lpj=83333) Dec 13 14:12:57.956538 kernel: pid_max: default: 32768 minimum: 301 Dec 13 14:12:57.956553 kernel: LSM: Security Framework initializing Dec 13 14:12:57.956573 kernel: SELinux: Initializing. Dec 13 14:12:57.956589 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Dec 13 14:12:57.956605 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Dec 13 14:12:57.956623 kernel: rcu: Hierarchical SRCU implementation. Dec 13 14:12:57.956639 kernel: Platform MSI: ITS@0x10080000 domain created Dec 13 14:12:57.956655 kernel: PCI/MSI: ITS@0x10080000 domain created Dec 13 14:12:57.956670 kernel: Remapping and enabling EFI services. Dec 13 14:12:57.956685 kernel: smp: Bringing up secondary CPUs ... Dec 13 14:12:57.956701 kernel: Detected PIPT I-cache on CPU1 Dec 13 14:12:57.956721 kernel: GICv3: CPU1: found redistributor 1 region 0:0x0000000010220000 Dec 13 14:12:57.956738 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000004000e0000 Dec 13 14:12:57.956753 kernel: CPU1: Booted secondary processor 0x0000000001 [0x410fd083] Dec 13 14:12:57.956769 kernel: smp: Brought up 1 node, 2 CPUs Dec 13 14:12:57.956784 kernel: SMP: Total of 2 processors activated. Dec 13 14:12:57.956799 kernel: CPU features: detected: 32-bit EL0 Support Dec 13 14:12:57.956815 kernel: CPU features: detected: 32-bit EL1 Support Dec 13 14:12:57.956830 kernel: CPU features: detected: CRC32 instructions Dec 13 14:12:57.956846 kernel: CPU: All CPU(s) started at EL1 Dec 13 14:12:57.956861 kernel: alternatives: patching kernel code Dec 13 14:12:57.956899 kernel: devtmpfs: initialized Dec 13 14:12:57.956916 kernel: KASLR disabled due to lack of seed Dec 13 14:12:57.956946 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Dec 13 14:12:57.956967 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Dec 13 14:12:57.956983 kernel: pinctrl core: initialized pinctrl subsystem Dec 13 14:12:57.956999 kernel: SMBIOS 3.0.0 present. Dec 13 14:12:57.957015 kernel: DMI: Amazon EC2 a1.large/, BIOS 1.0 11/1/2018 Dec 13 14:12:57.957031 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Dec 13 14:12:57.957047 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Dec 13 14:12:57.957063 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Dec 13 14:12:57.957080 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Dec 13 14:12:57.957101 kernel: audit: initializing netlink subsys (disabled) Dec 13 14:12:57.957117 kernel: audit: type=2000 audit(0.249:1): state=initialized audit_enabled=0 res=1 Dec 13 14:12:57.957133 kernel: thermal_sys: Registered thermal governor 'step_wise' Dec 13 14:12:57.957150 kernel: cpuidle: using governor menu Dec 13 14:12:57.957166 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Dec 13 14:12:57.957185 kernel: ASID allocator initialised with 32768 entries Dec 13 14:12:57.957202 kernel: ACPI: bus type PCI registered Dec 13 14:12:57.957218 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Dec 13 14:12:57.957234 kernel: Serial: AMBA PL011 UART driver Dec 13 14:12:57.957250 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Dec 13 14:12:57.957266 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Dec 13 14:12:57.957282 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Dec 13 14:12:57.957298 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Dec 13 14:12:57.957314 kernel: cryptd: max_cpu_qlen set to 1000 Dec 13 14:12:57.957334 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Dec 13 14:12:57.957351 kernel: ACPI: Added _OSI(Module Device) Dec 13 14:12:57.957367 kernel: ACPI: Added _OSI(Processor Device) Dec 13 14:12:57.957383 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Dec 13 14:12:57.957399 kernel: ACPI: Added _OSI(Processor Aggregator Device) Dec 13 14:12:57.957415 kernel: ACPI: Added _OSI(Linux-Dell-Video) Dec 13 14:12:57.957431 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Dec 13 14:12:57.957447 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Dec 13 14:12:57.957463 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Dec 13 14:12:57.957483 kernel: ACPI: Interpreter enabled Dec 13 14:12:57.957500 kernel: ACPI: Using GIC for interrupt routing Dec 13 14:12:57.957516 kernel: ACPI: MCFG table detected, 1 entries Dec 13 14:12:57.957532 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-0f]) Dec 13 14:12:57.957814 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Dec 13 14:12:57.958027 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Dec 13 14:12:57.958229 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Dec 13 14:12:57.958417 kernel: acpi PNP0A08:00: ECAM area [mem 0x20000000-0x20ffffff] reserved by PNP0C02:00 Dec 13 14:12:57.958604 kernel: acpi PNP0A08:00: ECAM at [mem 0x20000000-0x20ffffff] for [bus 00-0f] Dec 13 14:12:57.958626 kernel: ACPI: Remapped I/O 0x000000001fff0000 to [io 0x0000-0xffff window] Dec 13 14:12:57.958643 kernel: acpiphp: Slot [1] registered Dec 13 14:12:57.958659 kernel: acpiphp: Slot [2] registered Dec 13 14:12:57.958676 kernel: acpiphp: Slot [3] registered Dec 13 14:12:57.958691 kernel: acpiphp: Slot [4] registered Dec 13 14:12:57.958708 kernel: acpiphp: Slot [5] registered Dec 13 14:12:57.958739 kernel: acpiphp: Slot [6] registered Dec 13 14:12:57.958757 kernel: acpiphp: Slot [7] registered Dec 13 14:12:57.958779 kernel: acpiphp: Slot [8] registered Dec 13 14:12:57.958795 kernel: acpiphp: Slot [9] registered Dec 13 14:12:57.958811 kernel: acpiphp: Slot [10] registered Dec 13 14:12:57.958827 kernel: acpiphp: Slot [11] registered Dec 13 14:12:57.958843 kernel: acpiphp: Slot [12] registered Dec 13 14:12:57.958859 kernel: acpiphp: Slot [13] registered Dec 13 14:12:57.958893 kernel: acpiphp: Slot [14] registered Dec 13 14:12:57.958910 kernel: acpiphp: Slot [15] registered Dec 13 14:12:57.958926 kernel: acpiphp: Slot [16] registered Dec 13 14:12:57.958947 kernel: acpiphp: Slot [17] registered Dec 13 14:12:57.958963 kernel: acpiphp: Slot [18] registered Dec 13 14:12:57.958979 kernel: acpiphp: Slot [19] registered Dec 13 14:12:57.958995 kernel: acpiphp: Slot [20] registered Dec 13 14:12:57.959011 kernel: acpiphp: Slot [21] registered Dec 13 14:12:57.959027 kernel: acpiphp: Slot [22] registered Dec 13 14:12:57.959042 kernel: acpiphp: Slot [23] registered Dec 13 14:12:57.959058 kernel: acpiphp: Slot [24] registered Dec 13 14:12:57.959074 kernel: acpiphp: Slot [25] registered Dec 13 14:12:57.959090 kernel: acpiphp: Slot [26] registered Dec 13 14:12:57.959110 kernel: acpiphp: Slot [27] registered Dec 13 14:12:57.959126 kernel: acpiphp: Slot [28] registered Dec 13 14:12:57.959142 kernel: acpiphp: Slot [29] registered Dec 13 14:12:57.959157 kernel: acpiphp: Slot [30] registered Dec 13 14:12:57.959174 kernel: acpiphp: Slot [31] registered Dec 13 14:12:57.959190 kernel: PCI host bridge to bus 0000:00 Dec 13 14:12:57.959380 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xffffffff window] Dec 13 14:12:57.959550 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Dec 13 14:12:57.959722 kernel: pci_bus 0000:00: root bus resource [mem 0x400000000000-0x407fffffffff window] Dec 13 14:12:57.959930 kernel: pci_bus 0000:00: root bus resource [bus 00-0f] Dec 13 14:12:57.960143 kernel: pci 0000:00:00.0: [1d0f:0200] type 00 class 0x060000 Dec 13 14:12:57.960349 kernel: pci 0000:00:01.0: [1d0f:8250] type 00 class 0x070003 Dec 13 14:12:57.960547 kernel: pci 0000:00:01.0: reg 0x10: [mem 0x80118000-0x80118fff] Dec 13 14:12:57.960765 kernel: pci 0000:00:04.0: [1d0f:8061] type 00 class 0x010802 Dec 13 14:12:57.960989 kernel: pci 0000:00:04.0: reg 0x10: [mem 0x80114000-0x80117fff] Dec 13 14:12:57.961185 kernel: pci 0000:00:04.0: PME# supported from D0 D1 D2 D3hot D3cold Dec 13 14:12:57.961392 kernel: pci 0000:00:05.0: [1d0f:ec20] type 00 class 0x020000 Dec 13 14:12:57.961587 kernel: pci 0000:00:05.0: reg 0x10: [mem 0x80110000-0x80113fff] Dec 13 14:12:57.961771 kernel: pci 0000:00:05.0: reg 0x18: [mem 0x80000000-0x800fffff pref] Dec 13 14:12:57.967118 kernel: pci 0000:00:05.0: reg 0x20: [mem 0x80100000-0x8010ffff] Dec 13 14:12:57.967336 kernel: pci 0000:00:05.0: PME# supported from D0 D1 D2 D3hot D3cold Dec 13 14:12:57.967534 kernel: pci 0000:00:05.0: BAR 2: assigned [mem 0x80000000-0x800fffff pref] Dec 13 14:12:57.967744 kernel: pci 0000:00:05.0: BAR 4: assigned [mem 0x80100000-0x8010ffff] Dec 13 14:12:57.967981 kernel: pci 0000:00:04.0: BAR 0: assigned [mem 0x80110000-0x80113fff] Dec 13 14:12:57.968208 kernel: pci 0000:00:05.0: BAR 0: assigned [mem 0x80114000-0x80117fff] Dec 13 14:12:57.968412 kernel: pci 0000:00:01.0: BAR 0: assigned [mem 0x80118000-0x80118fff] Dec 13 14:12:57.968590 kernel: pci_bus 0000:00: resource 4 [mem 0x80000000-0xffffffff window] Dec 13 14:12:57.968760 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Dec 13 14:12:57.978923 kernel: pci_bus 0000:00: resource 6 [mem 0x400000000000-0x407fffffffff window] Dec 13 14:12:57.978970 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Dec 13 14:12:57.978989 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Dec 13 14:12:57.979007 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Dec 13 14:12:57.979024 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Dec 13 14:12:57.979041 kernel: iommu: Default domain type: Translated Dec 13 14:12:57.979058 kernel: iommu: DMA domain TLB invalidation policy: strict mode Dec 13 14:12:57.979074 kernel: vgaarb: loaded Dec 13 14:12:57.979090 kernel: pps_core: LinuxPPS API ver. 1 registered Dec 13 14:12:57.979117 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Dec 13 14:12:57.979134 kernel: PTP clock support registered Dec 13 14:12:57.979150 kernel: Registered efivars operations Dec 13 14:12:57.979167 kernel: clocksource: Switched to clocksource arch_sys_counter Dec 13 14:12:57.979183 kernel: VFS: Disk quotas dquot_6.6.0 Dec 13 14:12:57.979199 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Dec 13 14:12:57.979216 kernel: pnp: PnP ACPI init Dec 13 14:12:57.979426 kernel: system 00:00: [mem 0x20000000-0x2fffffff] could not be reserved Dec 13 14:12:57.979456 kernel: pnp: PnP ACPI: found 1 devices Dec 13 14:12:57.979473 kernel: NET: Registered PF_INET protocol family Dec 13 14:12:57.979490 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Dec 13 14:12:57.979506 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Dec 13 14:12:57.979523 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Dec 13 14:12:57.979540 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Dec 13 14:12:57.979557 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Dec 13 14:12:57.979573 kernel: TCP: Hash tables configured (established 32768 bind 32768) Dec 13 14:12:57.979589 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Dec 13 14:12:57.979610 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Dec 13 14:12:57.979626 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Dec 13 14:12:57.979642 kernel: PCI: CLS 0 bytes, default 64 Dec 13 14:12:57.979659 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 3 counters available Dec 13 14:12:57.979675 kernel: kvm [1]: HYP mode not available Dec 13 14:12:57.979691 kernel: Initialise system trusted keyrings Dec 13 14:12:57.979708 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Dec 13 14:12:57.979724 kernel: Key type asymmetric registered Dec 13 14:12:57.979740 kernel: Asymmetric key parser 'x509' registered Dec 13 14:12:57.979760 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Dec 13 14:12:57.979777 kernel: io scheduler mq-deadline registered Dec 13 14:12:57.979793 kernel: io scheduler kyber registered Dec 13 14:12:57.979809 kernel: io scheduler bfq registered Dec 13 14:12:57.980035 kernel: pl061_gpio ARMH0061:00: PL061 GPIO chip registered Dec 13 14:12:57.980061 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Dec 13 14:12:57.980078 kernel: ACPI: button: Power Button [PWRB] Dec 13 14:12:57.980095 kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0E:00/input/input1 Dec 13 14:12:57.980116 kernel: ACPI: button: Sleep Button [SLPB] Dec 13 14:12:57.980133 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Dec 13 14:12:57.980150 kernel: ACPI: \_SB_.PCI0.GSI2: Enabled at IRQ 37 Dec 13 14:12:57.980342 kernel: serial 0000:00:01.0: enabling device (0010 -> 0012) Dec 13 14:12:57.980366 kernel: printk: console [ttyS0] disabled Dec 13 14:12:57.980384 kernel: 0000:00:01.0: ttyS0 at MMIO 0x80118000 (irq = 14, base_baud = 115200) is a 16550A Dec 13 14:12:57.980400 kernel: printk: console [ttyS0] enabled Dec 13 14:12:57.980416 kernel: printk: bootconsole [uart0] disabled Dec 13 14:12:57.980432 kernel: thunder_xcv, ver 1.0 Dec 13 14:12:57.980452 kernel: thunder_bgx, ver 1.0 Dec 13 14:12:57.980469 kernel: nicpf, ver 1.0 Dec 13 14:12:57.980485 kernel: nicvf, ver 1.0 Dec 13 14:12:57.980676 kernel: rtc-efi rtc-efi.0: registered as rtc0 Dec 13 14:12:57.980854 kernel: rtc-efi rtc-efi.0: setting system clock to 2024-12-13T14:12:57 UTC (1734099177) Dec 13 14:12:57.980896 kernel: hid: raw HID events driver (C) Jiri Kosina Dec 13 14:12:57.980914 kernel: NET: Registered PF_INET6 protocol family Dec 13 14:12:57.980931 kernel: Segment Routing with IPv6 Dec 13 14:12:57.980947 kernel: In-situ OAM (IOAM) with IPv6 Dec 13 14:12:57.980969 kernel: NET: Registered PF_PACKET protocol family Dec 13 14:12:57.980986 kernel: Key type dns_resolver registered Dec 13 14:12:57.981002 kernel: registered taskstats version 1 Dec 13 14:12:57.981018 kernel: Loading compiled-in X.509 certificates Dec 13 14:12:57.981035 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.15.173-flatcar: e011ba9949ade5a6d03f7a5e28171f7f59e70f8a' Dec 13 14:12:57.981051 kernel: Key type .fscrypt registered Dec 13 14:12:57.981067 kernel: Key type fscrypt-provisioning registered Dec 13 14:12:57.981083 kernel: ima: No TPM chip found, activating TPM-bypass! Dec 13 14:12:57.981100 kernel: ima: Allocated hash algorithm: sha1 Dec 13 14:12:57.981120 kernel: ima: No architecture policies found Dec 13 14:12:57.981136 kernel: clk: Disabling unused clocks Dec 13 14:12:57.981152 kernel: Freeing unused kernel memory: 36416K Dec 13 14:12:57.981169 kernel: Run /init as init process Dec 13 14:12:57.981185 kernel: with arguments: Dec 13 14:12:57.981201 kernel: /init Dec 13 14:12:57.981217 kernel: with environment: Dec 13 14:12:57.981232 kernel: HOME=/ Dec 13 14:12:57.981248 kernel: TERM=linux Dec 13 14:12:57.981269 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Dec 13 14:12:57.981290 systemd[1]: systemd 252 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE -TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Dec 13 14:12:57.981313 systemd[1]: Detected virtualization amazon. Dec 13 14:12:57.981331 systemd[1]: Detected architecture arm64. Dec 13 14:12:57.981349 systemd[1]: Running in initrd. Dec 13 14:12:57.981366 systemd[1]: No hostname configured, using default hostname. Dec 13 14:12:57.981383 systemd[1]: Hostname set to . Dec 13 14:12:57.981406 systemd[1]: Initializing machine ID from VM UUID. Dec 13 14:12:57.981424 systemd[1]: Queued start job for default target initrd.target. Dec 13 14:12:57.981442 systemd[1]: Started systemd-ask-password-console.path. Dec 13 14:12:57.981459 systemd[1]: Reached target cryptsetup.target. Dec 13 14:12:57.981477 systemd[1]: Reached target paths.target. Dec 13 14:12:57.981494 systemd[1]: Reached target slices.target. Dec 13 14:12:57.981512 systemd[1]: Reached target swap.target. Dec 13 14:12:57.981529 systemd[1]: Reached target timers.target. Dec 13 14:12:57.981551 systemd[1]: Listening on iscsid.socket. Dec 13 14:12:57.981569 systemd[1]: Listening on iscsiuio.socket. Dec 13 14:12:57.981586 systemd[1]: Listening on systemd-journald-audit.socket. Dec 13 14:12:57.981604 systemd[1]: Listening on systemd-journald-dev-log.socket. Dec 13 14:12:57.981621 systemd[1]: Listening on systemd-journald.socket. Dec 13 14:12:57.981639 systemd[1]: Listening on systemd-networkd.socket. Dec 13 14:12:57.981657 systemd[1]: Listening on systemd-udevd-control.socket. Dec 13 14:12:57.981674 systemd[1]: Listening on systemd-udevd-kernel.socket. Dec 13 14:12:57.981696 systemd[1]: Reached target sockets.target. Dec 13 14:12:57.981714 systemd[1]: Starting kmod-static-nodes.service... Dec 13 14:12:57.981731 systemd[1]: Finished network-cleanup.service. Dec 13 14:12:57.981748 systemd[1]: Starting systemd-fsck-usr.service... Dec 13 14:12:57.981766 systemd[1]: Starting systemd-journald.service... Dec 13 14:12:57.981783 systemd[1]: Starting systemd-modules-load.service... Dec 13 14:12:57.981801 systemd[1]: Starting systemd-resolved.service... Dec 13 14:12:57.981818 systemd[1]: Starting systemd-vconsole-setup.service... Dec 13 14:12:57.981836 systemd[1]: Finished kmod-static-nodes.service. Dec 13 14:12:57.981857 kernel: audit: type=1130 audit(1734099177.941:2): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:57.981921 systemd[1]: Finished systemd-fsck-usr.service. Dec 13 14:12:57.981940 kernel: audit: type=1130 audit(1734099177.954:3): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-usr comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:57.981957 systemd[1]: Finished systemd-vconsole-setup.service. Dec 13 14:12:57.981975 kernel: audit: type=1130 audit(1734099177.969:4): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:57.981992 systemd[1]: Starting dracut-cmdline-ask.service... Dec 13 14:12:57.982013 systemd-journald[309]: Journal started Dec 13 14:12:57.982101 systemd-journald[309]: Runtime Journal (/run/log/journal/ec27bc6a5f32b41c3b9acc277702825f) is 8.0M, max 75.4M, 67.4M free. Dec 13 14:12:57.941000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:57.954000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-usr comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:57.969000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:57.964939 systemd-modules-load[310]: Inserted module 'overlay' Dec 13 14:12:58.007633 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... Dec 13 14:12:58.007696 systemd[1]: Started systemd-journald.service. Dec 13 14:12:58.023892 kernel: audit: type=1130 audit(1734099178.014:5): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:58.014000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:58.024001 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. Dec 13 14:12:58.035124 kernel: audit: type=1130 audit(1734099178.022: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' Dec 13 14:12:58.022000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:58.042413 systemd[1]: Finished dracut-cmdline-ask.service. Dec 13 14:12:58.046140 systemd[1]: Starting dracut-cmdline.service... Dec 13 14:12:58.056972 kernel: audit: type=1130 audit(1734099178.042: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' Dec 13 14:12:58.042000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:58.061714 systemd-resolved[311]: Positive Trust Anchors: Dec 13 14:12:58.067762 systemd-resolved[311]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Dec 13 14:12:58.070420 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Dec 13 14:12:58.070545 systemd-resolved[311]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Dec 13 14:12:58.082160 systemd-modules-load[310]: Inserted module 'br_netfilter' Dec 13 14:12:58.089032 kernel: Bridge firewalling registered Dec 13 14:12:58.103693 dracut-cmdline[326]: dracut-dracut-053 Dec 13 14:12:58.110906 kernel: SCSI subsystem initialized Dec 13 14:12:58.113066 dracut-cmdline[326]: Using kernel command line parameters: rd.driver.pre=btrfs BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlycon flatcar.first_boot=detected acpi=force flatcar.oem.id=ec2 modprobe.blacklist=xen_fbfront net.ifnames=0 nvme_core.io_timeout=4294967295 verity.usrhash=5997a8cf94b1df1856dc785f0a7074604bbf4c21fdcca24a1996021471a77601 Dec 13 14:12:58.136777 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Dec 13 14:12:58.136822 kernel: device-mapper: uevent: version 1.0.3 Dec 13 14:12:58.140907 kernel: device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com Dec 13 14:12:58.145855 systemd-modules-load[310]: Inserted module 'dm_multipath' Dec 13 14:12:58.148295 systemd[1]: Finished systemd-modules-load.service. Dec 13 14:12:58.149000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:58.152854 systemd[1]: Starting systemd-sysctl.service... Dec 13 14:12:58.163112 kernel: audit: type=1130 audit(1734099178.149:8): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:58.180498 systemd[1]: Finished systemd-sysctl.service. Dec 13 14:12:58.178000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:58.190897 kernel: audit: type=1130 audit(1734099178.178:9): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:58.282909 kernel: Loading iSCSI transport class v2.0-870. Dec 13 14:12:58.303909 kernel: iscsi: registered transport (tcp) Dec 13 14:12:58.328905 kernel: iscsi: registered transport (qla4xxx) Dec 13 14:12:58.328974 kernel: QLogic iSCSI HBA Driver Dec 13 14:12:58.550608 systemd-resolved[311]: Defaulting to hostname 'linux'. Dec 13 14:12:58.552346 kernel: random: crng init done Dec 13 14:12:58.554165 systemd[1]: Started systemd-resolved.service. Dec 13 14:12:58.563657 kernel: audit: type=1130 audit(1734099178.554:10): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:58.554000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:58.556228 systemd[1]: Reached target nss-lookup.target. Dec 13 14:12:58.581328 systemd[1]: Finished dracut-cmdline.service. Dec 13 14:12:58.582000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:58.585833 systemd[1]: Starting dracut-pre-udev.service... Dec 13 14:12:58.649911 kernel: raid6: neonx8 gen() 6451 MB/s Dec 13 14:12:58.667895 kernel: raid6: neonx8 xor() 4697 MB/s Dec 13 14:12:58.685895 kernel: raid6: neonx4 gen() 6629 MB/s Dec 13 14:12:58.703896 kernel: raid6: neonx4 xor() 4875 MB/s Dec 13 14:12:58.721895 kernel: raid6: neonx2 gen() 5846 MB/s Dec 13 14:12:58.739895 kernel: raid6: neonx2 xor() 4494 MB/s Dec 13 14:12:58.757895 kernel: raid6: neonx1 gen() 4521 MB/s Dec 13 14:12:58.775895 kernel: raid6: neonx1 xor() 3668 MB/s Dec 13 14:12:58.793895 kernel: raid6: int64x8 gen() 3449 MB/s Dec 13 14:12:58.811895 kernel: raid6: int64x8 xor() 2082 MB/s Dec 13 14:12:58.829895 kernel: raid6: int64x4 gen() 3858 MB/s Dec 13 14:12:58.847896 kernel: raid6: int64x4 xor() 2184 MB/s Dec 13 14:12:58.865895 kernel: raid6: int64x2 gen() 3625 MB/s Dec 13 14:12:58.883895 kernel: raid6: int64x2 xor() 1946 MB/s Dec 13 14:12:58.901895 kernel: raid6: int64x1 gen() 2767 MB/s Dec 13 14:12:58.921047 kernel: raid6: int64x1 xor() 1450 MB/s Dec 13 14:12:58.921076 kernel: raid6: using algorithm neonx4 gen() 6629 MB/s Dec 13 14:12:58.921100 kernel: raid6: .... xor() 4875 MB/s, rmw enabled Dec 13 14:12:58.922663 kernel: raid6: using neon recovery algorithm Dec 13 14:12:58.942201 kernel: xor: measuring software checksum speed Dec 13 14:12:58.942264 kernel: 8regs : 9298 MB/sec Dec 13 14:12:58.943955 kernel: 32regs : 11104 MB/sec Dec 13 14:12:58.945749 kernel: arm64_neon : 9582 MB/sec Dec 13 14:12:58.945780 kernel: xor: using function: 32regs (11104 MB/sec) Dec 13 14:12:59.036904 kernel: Btrfs loaded, crc32c=crc32c-generic, zoned=no, fsverity=no Dec 13 14:12:59.054480 systemd[1]: Finished dracut-pre-udev.service. Dec 13 14:12:59.055000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:59.057000 audit: BPF prog-id=7 op=LOAD Dec 13 14:12:59.057000 audit: BPF prog-id=8 op=LOAD Dec 13 14:12:59.059307 systemd[1]: Starting systemd-udevd.service... Dec 13 14:12:59.086822 systemd-udevd[509]: Using default interface naming scheme 'v252'. Dec 13 14:12:59.096789 systemd[1]: Started systemd-udevd.service. Dec 13 14:12:59.099000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:59.104578 systemd[1]: Starting dracut-pre-trigger.service... Dec 13 14:12:59.133514 dracut-pre-trigger[522]: rd.md=0: removing MD RAID activation Dec 13 14:12:59.196223 systemd[1]: Finished dracut-pre-trigger.service. Dec 13 14:12:59.196000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:59.200819 systemd[1]: Starting systemd-udev-trigger.service... Dec 13 14:12:59.302950 systemd[1]: Finished systemd-udev-trigger.service. Dec 13 14:12:59.317000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:12:59.426993 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Dec 13 14:12:59.427073 kernel: ena 0000:00:05.0: enabling device (0010 -> 0012) Dec 13 14:12:59.456015 kernel: ena 0000:00:05.0: ENA device version: 0.10 Dec 13 14:12:59.456235 kernel: ena 0000:00:05.0: ENA controller version: 0.0.1 implementation version 1 Dec 13 14:12:59.456439 kernel: ACPI: \_SB_.PCI0.GSI0: Enabled at IRQ 35 Dec 13 14:12:59.456464 kernel: nvme nvme0: pci function 0000:00:04.0 Dec 13 14:12:59.456696 kernel: ena 0000:00:05.0: Elastic Network Adapter (ENA) found at mem 80114000, mac addr 06:eb:49:9c:cd:ad Dec 13 14:12:59.462907 kernel: nvme nvme0: 2/0/0 default/read/poll queues Dec 13 14:12:59.470126 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Dec 13 14:12:59.470163 kernel: GPT:9289727 != 16777215 Dec 13 14:12:59.470186 kernel: GPT:Alternate GPT header not at the end of the disk. Dec 13 14:12:59.472130 kernel: GPT:9289727 != 16777215 Dec 13 14:12:59.473295 kernel: GPT: Use GNU Parted to correct GPT errors. Dec 13 14:12:59.476322 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Dec 13 14:12:59.480342 (udev-worker)[559]: Network interface NamePolicy= disabled on kernel command line. Dec 13 14:12:59.548902 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/nvme0n1p6 scanned by (udev-worker) (571) Dec 13 14:12:59.595949 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device. Dec 13 14:12:59.614191 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device. Dec 13 14:12:59.673035 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Dec 13 14:12:59.693379 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device. Dec 13 14:12:59.698109 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device. Dec 13 14:12:59.713959 systemd[1]: Starting disk-uuid.service... Dec 13 14:12:59.724078 disk-uuid[670]: Primary Header is updated. Dec 13 14:12:59.724078 disk-uuid[670]: Secondary Entries is updated. Dec 13 14:12:59.724078 disk-uuid[670]: Secondary Header is updated. Dec 13 14:12:59.731914 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Dec 13 14:12:59.739908 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Dec 13 14:12:59.746909 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Dec 13 14:13:00.753905 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Dec 13 14:13:00.755103 disk-uuid[671]: The operation has completed successfully. Dec 13 14:13:00.927339 systemd[1]: disk-uuid.service: Deactivated successfully. Dec 13 14:13:00.928000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=disk-uuid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:00.928000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=disk-uuid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:00.927538 systemd[1]: Finished disk-uuid.service. Dec 13 14:13:00.943108 systemd[1]: Starting verity-setup.service... Dec 13 14:13:00.976903 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Dec 13 14:13:01.068147 systemd[1]: Found device dev-mapper-usr.device. Dec 13 14:13:01.073727 systemd[1]: Mounting sysusr-usr.mount... Dec 13 14:13:01.081000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=verity-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.080679 systemd[1]: Finished verity-setup.service. Dec 13 14:13:01.168946 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: norecovery. Quota mode: none. Dec 13 14:13:01.169807 systemd[1]: Mounted sysusr-usr.mount. Dec 13 14:13:01.172613 systemd[1]: afterburn-network-kargs.service was skipped because no trigger condition checks were met. Dec 13 14:13:01.176355 systemd[1]: Starting ignition-setup.service... Dec 13 14:13:01.182859 systemd[1]: Starting parse-ip-for-networkd.service... Dec 13 14:13:01.211684 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Dec 13 14:13:01.211752 kernel: BTRFS info (device nvme0n1p6): using free space tree Dec 13 14:13:01.213785 kernel: BTRFS info (device nvme0n1p6): has skinny extents Dec 13 14:13:01.222891 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Dec 13 14:13:01.241366 systemd[1]: mnt-oem.mount: Deactivated successfully. Dec 13 14:13:01.258310 systemd[1]: Finished ignition-setup.service. Dec 13 14:13:01.258000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.263126 systemd[1]: Starting ignition-fetch-offline.service... Dec 13 14:13:01.325124 systemd[1]: Finished parse-ip-for-networkd.service. Dec 13 14:13:01.325000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=parse-ip-for-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.327000 audit: BPF prog-id=9 op=LOAD Dec 13 14:13:01.330121 systemd[1]: Starting systemd-networkd.service... Dec 13 14:13:01.375780 systemd-networkd[1183]: lo: Link UP Dec 13 14:13:01.375803 systemd-networkd[1183]: lo: Gained carrier Dec 13 14:13:01.379778 systemd-networkd[1183]: Enumeration completed Dec 13 14:13:01.381221 systemd[1]: Started systemd-networkd.service. Dec 13 14:13:01.381558 systemd-networkd[1183]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Dec 13 14:13:01.384000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.386089 systemd[1]: Reached target network.target. Dec 13 14:13:01.387983 systemd-networkd[1183]: eth0: Link UP Dec 13 14:13:01.387992 systemd-networkd[1183]: eth0: Gained carrier Dec 13 14:13:01.391397 systemd[1]: Starting iscsiuio.service... Dec 13 14:13:01.405884 systemd[1]: Started iscsiuio.service. Dec 13 14:13:01.407000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsiuio comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.410027 systemd-networkd[1183]: eth0: DHCPv4 address 172.31.23.153/20, gateway 172.31.16.1 acquired from 172.31.16.1 Dec 13 14:13:01.419540 systemd[1]: Starting iscsid.service... Dec 13 14:13:01.427774 iscsid[1188]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Dec 13 14:13:01.427774 iscsid[1188]: iscsid: Warning: InitiatorName file /etc/iscsi/initiatorname.iscsi does not exist or does not contain a properly formatted InitiatorName. If using software iscsi (iscsi_tcp or ib_iser) or partial offload (bnx2i or cxgbi iscsi), you may not be able to log into or discover targets. Please create a file /etc/iscsi/initiatorname.iscsi that contains a sting with the format: InitiatorName=iqn.yyyy-mm.[:identifier]. Dec 13 14:13:01.427774 iscsid[1188]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Dec 13 14:13:01.427774 iscsid[1188]: If using hardware iscsi like qla4xxx this message can be ignored. Dec 13 14:13:01.427774 iscsid[1188]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Dec 13 14:13:01.446241 iscsid[1188]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Dec 13 14:13:01.451013 systemd[1]: Started iscsid.service. Dec 13 14:13:01.449000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.468655 systemd[1]: Starting dracut-initqueue.service... Dec 13 14:13:01.490819 systemd[1]: Finished dracut-initqueue.service. Dec 13 14:13:01.492000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-initqueue comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:01.493999 systemd[1]: Reached target remote-fs-pre.target. Dec 13 14:13:01.496930 systemd[1]: Reached target remote-cryptsetup.target. Dec 13 14:13:01.503634 systemd[1]: Reached target remote-fs.target. Dec 13 14:13:01.507933 systemd[1]: Starting dracut-pre-mount.service... Dec 13 14:13:01.525745 systemd[1]: Finished dracut-pre-mount.service. Dec 13 14:13:01.524000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:02.239720 ignition[1127]: Ignition 2.14.0 Dec 13 14:13:02.239750 ignition[1127]: Stage: fetch-offline Dec 13 14:13:02.240197 ignition[1127]: reading system config file "/usr/lib/ignition/base.d/base.ign" Dec 13 14:13:02.240644 ignition[1127]: parsing config with SHA512: 6629d8e825d60c9c9d4629d8547ef9a0b839d6b01b7f61a481a1f23308c924b8b0bbf10cae7f7fe3bcaf88b23d1a81baa7771c3670728d4d2a1e665216a1de7b Dec 13 14:13:02.258845 ignition[1127]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Dec 13 14:13:02.261445 ignition[1127]: Ignition finished successfully Dec 13 14:13:02.264513 systemd[1]: Finished ignition-fetch-offline.service. Dec 13 14:13:02.277115 kernel: kauditd_printk_skb: 18 callbacks suppressed Dec 13 14:13:02.277158 kernel: audit: type=1130 audit(1734099182.265:29): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch-offline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:02.265000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch-offline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:02.278365 systemd[1]: Starting ignition-fetch.service... Dec 13 14:13:02.293646 ignition[1207]: Ignition 2.14.0 Dec 13 14:13:02.293671 ignition[1207]: Stage: fetch Dec 13 14:13:02.293996 ignition[1207]: reading system config file "/usr/lib/ignition/base.d/base.ign" Dec 13 14:13:02.294054 ignition[1207]: parsing config with SHA512: 6629d8e825d60c9c9d4629d8547ef9a0b839d6b01b7f61a481a1f23308c924b8b0bbf10cae7f7fe3bcaf88b23d1a81baa7771c3670728d4d2a1e665216a1de7b Dec 13 14:13:02.308657 ignition[1207]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Dec 13 14:13:02.311255 ignition[1207]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Dec 13 14:13:02.320139 ignition[1207]: INFO : PUT result: OK Dec 13 14:13:02.324403 ignition[1207]: DEBUG : parsed url from cmdline: "" Dec 13 14:13:02.326339 ignition[1207]: INFO : no config URL provided Dec 13 14:13:02.326339 ignition[1207]: INFO : reading system config file "/usr/lib/ignition/user.ign" Dec 13 14:13:02.326339 ignition[1207]: INFO : no config at "/usr/lib/ignition/user.ign" Dec 13 14:13:02.326339 ignition[1207]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Dec 13 14:13:02.334492 ignition[1207]: INFO : PUT result: OK Dec 13 14:13:02.334492 ignition[1207]: INFO : GET http://169.254.169.254/2019-10-01/user-data: attempt #1 Dec 13 14:13:02.338211 ignition[1207]: INFO : GET result: OK Dec 13 14:13:02.339588 ignition[1207]: DEBUG : parsing config with SHA512: fea5cf72f981896f8a2438cf5004ae5580bd1d610d88e38f6d6d5ea998aef9e028623e3ecf12a2aec6fe2927c11039abc0219744cf4acd3318dcef53beb2cc58 Dec 13 14:13:02.352340 unknown[1207]: fetched base config from "system" Dec 13 14:13:02.352630 unknown[1207]: fetched base config from "system" Dec 13 14:13:02.353349 ignition[1207]: fetch: fetch complete Dec 13 14:13:02.352645 unknown[1207]: fetched user config from "aws" Dec 13 14:13:02.353367 ignition[1207]: fetch: fetch passed Dec 13 14:13:02.353449 ignition[1207]: Ignition finished successfully Dec 13 14:13:02.363701 systemd[1]: Finished ignition-fetch.service. Dec 13 14:13:02.364000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:02.367995 systemd[1]: Starting ignition-kargs.service... Dec 13 14:13:02.378876 kernel: audit: type=1130 audit(1734099182.364:30): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:02.391550 ignition[1213]: Ignition 2.14.0 Dec 13 14:13:02.391578 ignition[1213]: Stage: kargs Dec 13 14:13:02.391903 ignition[1213]: reading system config file "/usr/lib/ignition/base.d/base.ign" Dec 13 14:13:02.391964 ignition[1213]: parsing config with SHA512: 6629d8e825d60c9c9d4629d8547ef9a0b839d6b01b7f61a481a1f23308c924b8b0bbf10cae7f7fe3bcaf88b23d1a81baa7771c3670728d4d2a1e665216a1de7b Dec 13 14:13:02.405326 ignition[1213]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Dec 13 14:13:02.407496 ignition[1213]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Dec 13 14:13:02.410235 ignition[1213]: INFO : PUT result: OK Dec 13 14:13:02.415593 ignition[1213]: kargs: kargs passed Dec 13 14:13:02.415701 ignition[1213]: Ignition finished successfully Dec 13 14:13:02.419953 systemd[1]: Finished ignition-kargs.service. Dec 13 14:13:02.420000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-kargs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:02.424279 systemd[1]: Starting ignition-disks.service... Dec 13 14:13:02.432668 kernel: audit: type=1130 audit(1734099182.420:31): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-kargs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:02.439949 ignition[1219]: Ignition 2.14.0 Dec 13 14:13:02.439977 ignition[1219]: Stage: disks Dec 13 14:13:02.440281 ignition[1219]: reading system config file "/usr/lib/ignition/base.d/base.ign" Dec 13 14:13:02.440339 ignition[1219]: parsing config with SHA512: 6629d8e825d60c9c9d4629d8547ef9a0b839d6b01b7f61a481a1f23308c924b8b0bbf10cae7f7fe3bcaf88b23d1a81baa7771c3670728d4d2a1e665216a1de7b Dec 13 14:13:02.454943 ignition[1219]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Dec 13 14:13:02.457508 ignition[1219]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Dec 13 14:13:02.461377 ignition[1219]: INFO : PUT result: OK Dec 13 14:13:02.466463 ignition[1219]: disks: disks passed Dec 13 14:13:02.468043 ignition[1219]: Ignition finished successfully Dec 13 14:13:02.471133 systemd[1]: Finished ignition-disks.service. Dec 13 14:13:02.472000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-disks comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:02.474162 systemd[1]: Reached target initrd-root-device.target. Dec 13 14:13:02.504416 kernel: audit: type=1130 audit(1734099182.472:32): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-disks comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:02.482491 systemd[1]: Reached target local-fs-pre.target. Dec 13 14:13:02.484090 systemd[1]: Reached target local-fs.target. Dec 13 14:13:02.485580 systemd[1]: Reached target sysinit.target. Dec 13 14:13:02.487066 systemd[1]: Reached target basic.target. Dec 13 14:13:02.489897 systemd[1]: Starting systemd-fsck-root.service... Dec 13 14:13:02.528818 systemd-fsck[1227]: ROOT: clean, 621/553520 files, 56020/553472 blocks Dec 13 14:13:02.539455 systemd[1]: Finished systemd-fsck-root.service. Dec 13 14:13:02.541000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:02.543769 systemd[1]: Mounting sysroot.mount... Dec 13 14:13:02.551708 kernel: audit: type=1130 audit(1734099182.541:33): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:02.571896 kernel: EXT4-fs (nvme0n1p9): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none. Dec 13 14:13:02.573371 systemd[1]: Mounted sysroot.mount. Dec 13 14:13:02.576310 systemd[1]: Reached target initrd-root-fs.target. Dec 13 14:13:02.583075 systemd[1]: Mounting sysroot-usr.mount... Dec 13 14:13:02.586810 systemd[1]: flatcar-metadata-hostname.service was skipped because no trigger condition checks were met. Dec 13 14:13:02.589791 systemd[1]: ignition-remount-sysroot.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Dec 13 14:13:02.590131 systemd[1]: Reached target ignition-diskful.target. Dec 13 14:13:02.603027 systemd[1]: Mounted sysroot-usr.mount. Dec 13 14:13:02.628166 systemd[1]: Mounting sysroot-usr-share-oem.mount... Dec 13 14:13:02.632490 systemd[1]: Starting initrd-setup-root.service... Dec 13 14:13:02.644682 initrd-setup-root[1249]: cut: /sysroot/etc/passwd: No such file or directory Dec 13 14:13:02.661018 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/nvme0n1p6 scanned by mount (1244) Dec 13 14:13:02.665586 initrd-setup-root[1257]: cut: /sysroot/etc/group: No such file or directory Dec 13 14:13:02.669246 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Dec 13 14:13:02.669281 kernel: BTRFS info (device nvme0n1p6): using free space tree Dec 13 14:13:02.672937 kernel: BTRFS info (device nvme0n1p6): has skinny extents Dec 13 14:13:02.675594 initrd-setup-root[1273]: cut: /sysroot/etc/shadow: No such file or directory Dec 13 14:13:02.685853 initrd-setup-root[1289]: cut: /sysroot/etc/gshadow: No such file or directory Dec 13 14:13:02.691913 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Dec 13 14:13:02.697809 systemd[1]: Mounted sysroot-usr-share-oem.mount. Dec 13 14:13:02.975152 systemd[1]: Finished initrd-setup-root.service. Dec 13 14:13:02.976000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:02.979475 systemd[1]: Starting ignition-mount.service... Dec 13 14:13:02.988469 kernel: audit: type=1130 audit(1734099182.976:34): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:02.988995 systemd[1]: Starting sysroot-boot.service... Dec 13 14:13:02.998191 systemd[1]: sysusr-usr-share-oem.mount: Deactivated successfully. Dec 13 14:13:02.998358 systemd[1]: sysroot-usr-share-oem.mount: Deactivated successfully. Dec 13 14:13:03.018917 ignition[1309]: INFO : Ignition 2.14.0 Dec 13 14:13:03.020802 ignition[1309]: INFO : Stage: mount Dec 13 14:13:03.022475 ignition[1309]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign" Dec 13 14:13:03.024854 ignition[1309]: DEBUG : parsing config with SHA512: 6629d8e825d60c9c9d4629d8547ef9a0b839d6b01b7f61a481a1f23308c924b8b0bbf10cae7f7fe3bcaf88b23d1a81baa7771c3670728d4d2a1e665216a1de7b Dec 13 14:13:03.057448 systemd[1]: Finished sysroot-boot.service. Dec 13 14:13:03.058000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=sysroot-boot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.061482 ignition[1309]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Dec 13 14:13:03.069496 ignition[1309]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Dec 13 14:13:03.071775 kernel: audit: type=1130 audit(1734099183.058:35): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=sysroot-boot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.071888 ignition[1309]: INFO : PUT result: OK Dec 13 14:13:03.076714 ignition[1309]: INFO : mount: mount passed Dec 13 14:13:03.078247 ignition[1309]: INFO : Ignition finished successfully Dec 13 14:13:03.081417 systemd[1]: Finished ignition-mount.service. Dec 13 14:13:03.083000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.085597 systemd[1]: Starting ignition-files.service... Dec 13 14:13:03.093858 kernel: audit: type=1130 audit(1734099183.083:36): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.101969 systemd[1]: Mounting sysroot-usr-share-oem.mount... Dec 13 14:13:03.125901 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/nvme0n1p6 scanned by mount (1319) Dec 13 14:13:03.125965 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Dec 13 14:13:03.130751 kernel: BTRFS info (device nvme0n1p6): using free space tree Dec 13 14:13:03.130784 kernel: BTRFS info (device nvme0n1p6): has skinny extents Dec 13 14:13:03.139899 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Dec 13 14:13:03.145498 systemd[1]: Mounted sysroot-usr-share-oem.mount. Dec 13 14:13:03.164983 ignition[1338]: INFO : Ignition 2.14.0 Dec 13 14:13:03.168380 ignition[1338]: INFO : Stage: files Dec 13 14:13:03.168380 ignition[1338]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign" Dec 13 14:13:03.168380 ignition[1338]: DEBUG : parsing config with SHA512: 6629d8e825d60c9c9d4629d8547ef9a0b839d6b01b7f61a481a1f23308c924b8b0bbf10cae7f7fe3bcaf88b23d1a81baa7771c3670728d4d2a1e665216a1de7b Dec 13 14:13:03.183886 ignition[1338]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Dec 13 14:13:03.186276 ignition[1338]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Dec 13 14:13:03.189411 ignition[1338]: INFO : PUT result: OK Dec 13 14:13:03.194437 ignition[1338]: DEBUG : files: compiled without relabeling support, skipping Dec 13 14:13:03.198820 ignition[1338]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Dec 13 14:13:03.198820 ignition[1338]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Dec 13 14:13:03.265715 ignition[1338]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Dec 13 14:13:03.268647 ignition[1338]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Dec 13 14:13:03.273026 unknown[1338]: wrote ssh authorized keys file for user: core Dec 13 14:13:03.275073 ignition[1338]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Dec 13 14:13:03.277619 ignition[1338]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/eks/bootstrap.sh" Dec 13 14:13:03.281021 ignition[1338]: INFO : oem config not found in "/usr/share/oem", looking on oem partition Dec 13 14:13:03.289950 ignition[1338]: INFO : op(1): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem157969521" Dec 13 14:13:03.296134 ignition[1338]: CRITICAL : op(1): [failed] mounting "/dev/disk/by-label/OEM" at "/mnt/oem157969521": device or resource busy Dec 13 14:13:03.296134 ignition[1338]: ERROR : failed to mount ext4 device "/dev/disk/by-label/OEM" at "/mnt/oem157969521", trying btrfs: device or resource busy Dec 13 14:13:03.296134 ignition[1338]: INFO : op(2): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem157969521" Dec 13 14:13:03.305062 kernel: BTRFS info: devid 1 device path /dev/nvme0n1p6 changed to /dev/disk/by-label/OEM scanned by ignition (1341) Dec 13 14:13:03.305173 ignition[1338]: INFO : op(2): [finished] mounting "/dev/disk/by-label/OEM" at "/mnt/oem157969521" Dec 13 14:13:03.313810 ignition[1338]: INFO : op(3): [started] unmounting "/mnt/oem157969521" Dec 13 14:13:03.316104 ignition[1338]: INFO : op(3): [finished] unmounting "/mnt/oem157969521" Dec 13 14:13:03.316104 ignition[1338]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/eks/bootstrap.sh" Dec 13 14:13:03.316104 ignition[1338]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Dec 13 14:13:03.316104 ignition[1338]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Dec 13 14:13:03.316104 ignition[1338]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing file "/sysroot/etc/amazon/ssm/amazon-ssm-agent.json" Dec 13 14:13:03.316104 ignition[1338]: INFO : oem config not found in "/usr/share/oem", looking on oem partition Dec 13 14:13:03.338413 ignition[1338]: INFO : op(4): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem4054051348" Dec 13 14:13:03.338413 ignition[1338]: CRITICAL : op(4): [failed] mounting "/dev/disk/by-label/OEM" at "/mnt/oem4054051348": device or resource busy Dec 13 14:13:03.338413 ignition[1338]: ERROR : failed to mount ext4 device "/dev/disk/by-label/OEM" at "/mnt/oem4054051348", trying btrfs: device or resource busy Dec 13 14:13:03.338413 ignition[1338]: INFO : op(5): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem4054051348" Dec 13 14:13:03.338413 ignition[1338]: INFO : op(5): [finished] mounting "/dev/disk/by-label/OEM" at "/mnt/oem4054051348" Dec 13 14:13:03.338413 ignition[1338]: INFO : op(6): [started] unmounting "/mnt/oem4054051348" Dec 13 14:13:03.338413 ignition[1338]: INFO : op(6): [finished] unmounting "/mnt/oem4054051348" Dec 13 14:13:03.338413 ignition[1338]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing file "/sysroot/etc/amazon/ssm/amazon-ssm-agent.json" Dec 13 14:13:03.338413 ignition[1338]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/etc/amazon/ssm/seelog.xml" Dec 13 14:13:03.338413 ignition[1338]: INFO : oem config not found in "/usr/share/oem", looking on oem partition Dec 13 14:13:03.373275 ignition[1338]: INFO : op(7): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem2189039147" Dec 13 14:13:03.375937 ignition[1338]: CRITICAL : op(7): [failed] mounting "/dev/disk/by-label/OEM" at "/mnt/oem2189039147": device or resource busy Dec 13 14:13:03.378957 ignition[1338]: ERROR : failed to mount ext4 device "/dev/disk/by-label/OEM" at "/mnt/oem2189039147", trying btrfs: device or resource busy Dec 13 14:13:03.382270 ignition[1338]: INFO : op(8): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem2189039147" Dec 13 14:13:03.385521 ignition[1338]: INFO : op(8): [finished] mounting "/dev/disk/by-label/OEM" at "/mnt/oem2189039147" Dec 13 14:13:03.388327 ignition[1338]: INFO : op(9): [started] unmounting "/mnt/oem2189039147" Dec 13 14:13:03.390372 ignition[1338]: INFO : op(9): [finished] unmounting "/mnt/oem2189039147" Dec 13 14:13:03.392490 ignition[1338]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/etc/amazon/ssm/seelog.xml" Dec 13 14:13:03.396190 ignition[1338]: INFO : files: createFilesystemsFiles: createFiles: op(7): [started] writing file "/sysroot/etc/systemd/system/nvidia.service" Dec 13 14:13:03.399746 ignition[1338]: INFO : oem config not found in "/usr/share/oem", looking on oem partition Dec 13 14:13:03.409288 systemd-networkd[1183]: eth0: Gained IPv6LL Dec 13 14:13:03.417674 ignition[1338]: INFO : op(a): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem919892939" Dec 13 14:13:03.420504 ignition[1338]: CRITICAL : op(a): [failed] mounting "/dev/disk/by-label/OEM" at "/mnt/oem919892939": device or resource busy Dec 13 14:13:03.420504 ignition[1338]: ERROR : failed to mount ext4 device "/dev/disk/by-label/OEM" at "/mnt/oem919892939", trying btrfs: device or resource busy Dec 13 14:13:03.420504 ignition[1338]: INFO : op(b): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem919892939" Dec 13 14:13:03.420504 ignition[1338]: INFO : op(b): [finished] mounting "/dev/disk/by-label/OEM" at "/mnt/oem919892939" Dec 13 14:13:03.420504 ignition[1338]: INFO : op(c): [started] unmounting "/mnt/oem919892939" Dec 13 14:13:03.420504 ignition[1338]: INFO : op(c): [finished] unmounting "/mnt/oem919892939" Dec 13 14:13:03.443243 ignition[1338]: INFO : files: createFilesystemsFiles: createFiles: op(7): [finished] writing file "/sysroot/etc/systemd/system/nvidia.service" Dec 13 14:13:03.443243 ignition[1338]: INFO : files: op(8): [started] processing unit "coreos-metadata-sshkeys@.service" Dec 13 14:13:03.443243 ignition[1338]: INFO : files: op(8): [finished] processing unit "coreos-metadata-sshkeys@.service" Dec 13 14:13:03.443243 ignition[1338]: INFO : files: op(9): [started] processing unit "amazon-ssm-agent.service" Dec 13 14:13:03.443243 ignition[1338]: INFO : files: op(9): op(a): [started] writing unit "amazon-ssm-agent.service" at "/sysroot/etc/systemd/system/amazon-ssm-agent.service" Dec 13 14:13:03.443243 ignition[1338]: INFO : files: op(9): op(a): [finished] writing unit "amazon-ssm-agent.service" at "/sysroot/etc/systemd/system/amazon-ssm-agent.service" Dec 13 14:13:03.443243 ignition[1338]: INFO : files: op(9): [finished] processing unit "amazon-ssm-agent.service" Dec 13 14:13:03.443243 ignition[1338]: INFO : files: op(b): [started] processing unit "nvidia.service" Dec 13 14:13:03.443243 ignition[1338]: INFO : files: op(b): [finished] processing unit "nvidia.service" Dec 13 14:13:03.443243 ignition[1338]: INFO : files: op(c): [started] processing unit "etcd-member.service" Dec 13 14:13:03.443243 ignition[1338]: INFO : files: op(c): op(d): [started] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" Dec 13 14:13:03.443243 ignition[1338]: INFO : files: op(c): op(d): [finished] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" Dec 13 14:13:03.443243 ignition[1338]: INFO : files: op(c): [finished] processing unit "etcd-member.service" Dec 13 14:13:03.443243 ignition[1338]: INFO : files: op(e): [started] setting preset to enabled for "nvidia.service" Dec 13 14:13:03.443243 ignition[1338]: INFO : files: op(e): [finished] setting preset to enabled for "nvidia.service" Dec 13 14:13:03.443243 ignition[1338]: INFO : files: op(f): [started] setting preset to enabled for "etcd-member.service" Dec 13 14:13:03.443243 ignition[1338]: INFO : files: op(f): [finished] setting preset to enabled for "etcd-member.service" Dec 13 14:13:03.443243 ignition[1338]: INFO : files: op(10): [started] setting preset to enabled for "coreos-metadata-sshkeys@.service " Dec 13 14:13:03.443243 ignition[1338]: INFO : files: op(10): [finished] setting preset to enabled for "coreos-metadata-sshkeys@.service " Dec 13 14:13:03.443243 ignition[1338]: INFO : files: op(11): [started] setting preset to enabled for "amazon-ssm-agent.service" Dec 13 14:13:03.518917 kernel: audit: type=1130 audit(1734099183.475:37): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.475000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.474561 systemd[1]: Finished ignition-files.service. Dec 13 14:13:03.522173 ignition[1338]: INFO : files: op(11): [finished] setting preset to enabled for "amazon-ssm-agent.service" Dec 13 14:13:03.522173 ignition[1338]: INFO : files: createResultFile: createFiles: op(12): [started] writing file "/sysroot/etc/.ignition-result.json" Dec 13 14:13:03.522173 ignition[1338]: INFO : files: createResultFile: createFiles: op(12): [finished] writing file "/sysroot/etc/.ignition-result.json" Dec 13 14:13:03.522173 ignition[1338]: INFO : files: files passed Dec 13 14:13:03.522173 ignition[1338]: INFO : Ignition finished successfully Dec 13 14:13:03.499661 systemd[1]: Starting initrd-setup-root-after-ignition.service... Dec 13 14:13:03.512485 systemd[1]: torcx-profile-populate.service was skipped because of an unmet condition check (ConditionPathExists=/sysroot/etc/torcx/next-profile). Dec 13 14:13:03.549471 kernel: audit: type=1130 audit(1734099183.537:38): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.537000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.542000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.514821 systemd[1]: Starting ignition-quench.service... Dec 13 14:13:03.551292 initrd-setup-root-after-ignition[1363]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Dec 13 14:13:03.551000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root-after-ignition comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.525148 systemd[1]: ignition-quench.service: Deactivated successfully. Dec 13 14:13:03.525343 systemd[1]: Finished ignition-quench.service. Dec 13 14:13:03.551246 systemd[1]: Finished initrd-setup-root-after-ignition.service. Dec 13 14:13:03.553357 systemd[1]: Reached target ignition-complete.target. Dec 13 14:13:03.556539 systemd[1]: Starting initrd-parse-etc.service... Dec 13 14:13:03.595581 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Dec 13 14:13:03.596417 systemd[1]: Finished initrd-parse-etc.service. Dec 13 14:13:03.597000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.597000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.599460 systemd[1]: Reached target initrd-fs.target. Dec 13 14:13:03.601901 systemd[1]: Reached target initrd.target. Dec 13 14:13:03.604838 systemd[1]: dracut-mount.service was skipped because no trigger condition checks were met. Dec 13 14:13:03.606345 systemd[1]: Starting dracut-pre-pivot.service... Dec 13 14:13:03.632109 systemd[1]: Finished dracut-pre-pivot.service. Dec 13 14:13:03.630000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-pivot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.634347 systemd[1]: Starting initrd-cleanup.service... Dec 13 14:13:03.655060 systemd[1]: Stopped target nss-lookup.target. Dec 13 14:13:03.658486 systemd[1]: Stopped target remote-cryptsetup.target. Dec 13 14:13:03.661987 systemd[1]: Stopped target timers.target. Dec 13 14:13:03.664916 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Dec 13 14:13:03.666966 systemd[1]: Stopped dracut-pre-pivot.service. Dec 13 14:13:03.668000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-pivot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.670391 systemd[1]: Stopped target initrd.target. Dec 13 14:13:03.673388 systemd[1]: Stopped target basic.target. Dec 13 14:13:03.676304 systemd[1]: Stopped target ignition-complete.target. Dec 13 14:13:03.679642 systemd[1]: Stopped target ignition-diskful.target. Dec 13 14:13:03.682830 systemd[1]: Stopped target initrd-root-device.target. Dec 13 14:13:03.686213 systemd[1]: Stopped target remote-fs.target. Dec 13 14:13:03.689139 systemd[1]: Stopped target remote-fs-pre.target. Dec 13 14:13:03.692254 systemd[1]: Stopped target sysinit.target. Dec 13 14:13:03.695101 systemd[1]: Stopped target local-fs.target. Dec 13 14:13:03.698051 systemd[1]: Stopped target local-fs-pre.target. Dec 13 14:13:03.701167 systemd[1]: Stopped target swap.target. Dec 13 14:13:03.703758 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Dec 13 14:13:03.705648 systemd[1]: Stopped dracut-pre-mount.service. Dec 13 14:13:03.707000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.708844 systemd[1]: Stopped target cryptsetup.target. Dec 13 14:13:03.724355 systemd[1]: dracut-initqueue.service: Deactivated successfully. Dec 13 14:13:03.726267 systemd[1]: Stopped dracut-initqueue.service. Dec 13 14:13:03.727000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-initqueue comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.729288 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Dec 13 14:13:03.731580 systemd[1]: Stopped initrd-setup-root-after-ignition.service. Dec 13 14:13:03.733000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root-after-ignition comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.735173 systemd[1]: ignition-files.service: Deactivated successfully. Dec 13 14:13:03.737007 systemd[1]: Stopped ignition-files.service. Dec 13 14:13:03.738000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.741408 systemd[1]: Stopping ignition-mount.service... Dec 13 14:13:03.743381 systemd[1]: Stopping iscsiuio.service... Dec 13 14:13:03.757435 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Dec 13 14:13:03.759695 ignition[1376]: INFO : Ignition 2.14.0 Dec 13 14:13:03.759695 ignition[1376]: INFO : Stage: umount Dec 13 14:13:03.762985 ignition[1376]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign" Dec 13 14:13:03.762985 ignition[1376]: DEBUG : parsing config with SHA512: 6629d8e825d60c9c9d4629d8547ef9a0b839d6b01b7f61a481a1f23308c924b8b0bbf10cae7f7fe3bcaf88b23d1a81baa7771c3670728d4d2a1e665216a1de7b Dec 13 14:13:03.780431 ignition[1376]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Dec 13 14:13:03.780431 ignition[1376]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Dec 13 14:13:03.785990 ignition[1376]: INFO : PUT result: OK Dec 13 14:13:03.784943 systemd[1]: Stopped kmod-static-nodes.service. Dec 13 14:13:03.788000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.795614 ignition[1376]: INFO : umount: umount passed Dec 13 14:13:03.795614 ignition[1376]: INFO : Ignition finished successfully Dec 13 14:13:03.791995 systemd[1]: Stopping sysroot-boot.service... Dec 13 14:13:03.795040 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Dec 13 14:13:03.795425 systemd[1]: Stopped systemd-udev-trigger.service. Dec 13 14:13:03.802000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.804361 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Dec 13 14:13:03.804577 systemd[1]: Stopped dracut-pre-trigger.service. Dec 13 14:13:03.808000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.812856 systemd[1]: iscsiuio.service: Deactivated successfully. Dec 13 14:13:03.814584 systemd[1]: Stopped iscsiuio.service. Dec 13 14:13:03.816000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsiuio comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.817967 systemd[1]: ignition-mount.service: Deactivated successfully. Dec 13 14:13:03.819786 systemd[1]: Stopped ignition-mount.service. Dec 13 14:13:03.821000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.823305 systemd[1]: ignition-disks.service: Deactivated successfully. Dec 13 14:13:03.823000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-disks comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.825000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-kargs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.827000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.829000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch-offline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.823489 systemd[1]: Stopped ignition-disks.service. Dec 13 14:13:03.825154 systemd[1]: ignition-kargs.service: Deactivated successfully. Dec 13 14:13:03.825250 systemd[1]: Stopped ignition-kargs.service. Dec 13 14:13:03.826911 systemd[1]: ignition-fetch.service: Deactivated successfully. Dec 13 14:13:03.826998 systemd[1]: Stopped ignition-fetch.service. Dec 13 14:13:03.828591 systemd[1]: Stopped target network.target. Dec 13 14:13:03.830094 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Dec 13 14:13:03.830184 systemd[1]: Stopped ignition-fetch-offline.service. Dec 13 14:13:03.857000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.831921 systemd[1]: Stopped target paths.target. Dec 13 14:13:03.833361 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Dec 13 14:13:03.842006 systemd[1]: Stopped systemd-ask-password-console.path. Dec 13 14:13:03.849131 systemd[1]: Stopped target slices.target. Dec 13 14:13:03.852153 systemd[1]: Stopped target sockets.target. Dec 13 14:13:03.853676 systemd[1]: iscsid.socket: Deactivated successfully. Dec 13 14:13:03.853742 systemd[1]: Closed iscsid.socket. Dec 13 14:13:03.855118 systemd[1]: iscsiuio.socket: Deactivated successfully. Dec 13 14:13:03.855191 systemd[1]: Closed iscsiuio.socket. Dec 13 14:13:03.856548 systemd[1]: ignition-setup.service: Deactivated successfully. Dec 13 14:13:03.881000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.883000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.883000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.856641 systemd[1]: Stopped ignition-setup.service. Dec 13 14:13:03.859307 systemd[1]: Stopping systemd-networkd.service... Dec 13 14:13:03.866946 systemd-networkd[1183]: eth0: DHCPv6 lease lost Dec 13 14:13:03.873285 systemd[1]: Stopping systemd-resolved.service... Dec 13 14:13:03.889000 audit: BPF prog-id=9 op=UNLOAD Dec 13 14:13:03.877664 systemd[1]: systemd-networkd.service: Deactivated successfully. Dec 13 14:13:03.878264 systemd[1]: Stopped systemd-networkd.service. Dec 13 14:13:03.883166 systemd[1]: initrd-cleanup.service: Deactivated successfully. Dec 13 14:13:03.883367 systemd[1]: Finished initrd-cleanup.service. Dec 13 14:13:03.891082 systemd[1]: systemd-resolved.service: Deactivated successfully. Dec 13 14:13:03.907000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.891289 systemd[1]: Stopped systemd-resolved.service. Dec 13 14:13:03.914574 systemd[1]: sysroot-boot.mount: Deactivated successfully. Dec 13 14:13:03.915000 audit: BPF prog-id=6 op=UNLOAD Dec 13 14:13:03.916825 systemd[1]: systemd-networkd.socket: Deactivated successfully. Dec 13 14:13:03.916938 systemd[1]: Closed systemd-networkd.socket. Dec 13 14:13:03.923985 systemd[1]: Stopping network-cleanup.service... Dec 13 14:13:03.928000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=parse-ip-for-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.931000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.925445 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Dec 13 14:13:03.935000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.925564 systemd[1]: Stopped parse-ip-for-networkd.service. Dec 13 14:13:03.930242 systemd[1]: systemd-sysctl.service: Deactivated successfully. Dec 13 14:13:03.930339 systemd[1]: Stopped systemd-sysctl.service. Dec 13 14:13:03.933070 systemd[1]: systemd-modules-load.service: Deactivated successfully. Dec 13 14:13:03.933351 systemd[1]: Stopped systemd-modules-load.service. Dec 13 14:13:03.941076 systemd[1]: Stopping systemd-udevd.service... Dec 13 14:13:03.957547 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Dec 13 14:13:03.961178 systemd[1]: systemd-udevd.service: Deactivated successfully. Dec 13 14:13:03.962621 systemd[1]: Stopped systemd-udevd.service. Dec 13 14:13:03.962000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.965000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=sysroot-boot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.965547 systemd[1]: sysroot-boot.service: Deactivated successfully. Dec 13 14:13:03.965732 systemd[1]: Stopped sysroot-boot.service. Dec 13 14:13:03.971000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.971000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.971000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.973000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.975000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.969117 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Dec 13 14:13:03.969216 systemd[1]: Closed systemd-udevd-control.socket. Dec 13 14:13:03.972478 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Dec 13 14:13:03.995000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=network-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:03.972556 systemd[1]: Closed systemd-udevd-kernel.socket. Dec 13 14:13:03.972673 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Dec 13 14:13:03.972750 systemd[1]: Stopped dracut-pre-udev.service. Dec 13 14:13:03.973367 systemd[1]: dracut-cmdline.service: Deactivated successfully. Dec 13 14:13:03.973442 systemd[1]: Stopped dracut-cmdline.service. Dec 13 14:13:03.973632 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Dec 13 14:13:03.973702 systemd[1]: Stopped dracut-cmdline-ask.service. Dec 13 14:13:03.974520 systemd[1]: initrd-setup-root.service: Deactivated successfully. Dec 13 14:13:03.974591 systemd[1]: Stopped initrd-setup-root.service. Dec 13 14:13:03.976140 systemd[1]: Starting initrd-udevadm-cleanup-db.service... Dec 13 14:13:03.976629 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Dec 13 14:13:03.976728 systemd[1]: Stopped systemd-vconsole-setup.service. Dec 13 14:13:03.989818 systemd[1]: network-cleanup.service: Deactivated successfully. Dec 13 14:13:03.990251 systemd[1]: Stopped network-cleanup.service. Dec 13 14:13:04.017715 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Dec 13 14:13:04.020861 systemd[1]: Finished initrd-udevadm-cleanup-db.service. Dec 13 14:13:04.023000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-udevadm-cleanup-db comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:04.023000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-udevadm-cleanup-db comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:04.026155 systemd[1]: Reached target initrd-switch-root.target. Dec 13 14:13:04.030630 systemd[1]: Starting initrd-switch-root.service... Dec 13 14:13:04.047835 systemd[1]: Switching root. Dec 13 14:13:04.074368 iscsid[1188]: iscsid shutting down. Dec 13 14:13:04.076010 systemd-journald[309]: Received SIGTERM from PID 1 (n/a). Dec 13 14:13:04.076095 systemd-journald[309]: Journal stopped Dec 13 14:13:11.279179 kernel: SELinux: Class mctp_socket not defined in policy. Dec 13 14:13:11.279302 kernel: SELinux: Class anon_inode not defined in policy. Dec 13 14:13:11.279336 kernel: SELinux: the above unknown classes and permissions will be allowed Dec 13 14:13:11.279367 kernel: SELinux: policy capability network_peer_controls=1 Dec 13 14:13:11.279399 kernel: SELinux: policy capability open_perms=1 Dec 13 14:13:11.279430 kernel: SELinux: policy capability extended_socket_class=1 Dec 13 14:13:11.279461 kernel: SELinux: policy capability always_check_network=0 Dec 13 14:13:11.279499 kernel: SELinux: policy capability cgroup_seclabel=1 Dec 13 14:13:11.279529 kernel: SELinux: policy capability nnp_nosuid_transition=1 Dec 13 14:13:11.279559 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Dec 13 14:13:11.279590 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Dec 13 14:13:11.279632 systemd[1]: Successfully loaded SELinux policy in 140.669ms. Dec 13 14:13:11.279688 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 30.926ms. Dec 13 14:13:11.279721 systemd[1]: systemd 252 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE -TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Dec 13 14:13:11.279755 systemd[1]: Detected virtualization amazon. Dec 13 14:13:11.279790 systemd[1]: Detected architecture arm64. Dec 13 14:13:11.279819 systemd[1]: Detected first boot. Dec 13 14:13:11.279854 systemd[1]: Initializing machine ID from VM UUID. Dec 13 14:13:11.279942 kernel: SELinux: Context system_u:object_r:container_file_t:s0:c1022,c1023 is not valid (left unmapped). Dec 13 14:13:11.279977 systemd[1]: Populated /etc with preset unit settings. Dec 13 14:13:11.280011 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Dec 13 14:13:11.280046 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Dec 13 14:13:11.280079 systemd[1]: /run/systemd/system/docker.socket:8: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Dec 13 14:13:11.280115 kernel: kauditd_printk_skb: 55 callbacks suppressed Dec 13 14:13:11.280146 kernel: audit: type=1334 audit(1734099190.820:87): prog-id=12 op=LOAD Dec 13 14:13:11.280177 kernel: audit: type=1334 audit(1734099190.820:88): prog-id=3 op=UNLOAD Dec 13 14:13:11.280206 kernel: audit: type=1334 audit(1734099190.820:89): prog-id=13 op=LOAD Dec 13 14:13:11.280236 kernel: audit: type=1334 audit(1734099190.820:90): prog-id=14 op=LOAD Dec 13 14:13:11.280263 kernel: audit: type=1334 audit(1734099190.821:91): prog-id=4 op=UNLOAD Dec 13 14:13:11.280296 systemd[1]: iscsid.service: Deactivated successfully. Dec 13 14:13:11.280330 kernel: audit: type=1334 audit(1734099190.821:92): prog-id=5 op=UNLOAD Dec 13 14:13:11.280360 systemd[1]: Stopped iscsid.service. Dec 13 14:13:11.280394 kernel: audit: type=1334 audit(1734099190.825:93): prog-id=15 op=LOAD Dec 13 14:13:11.280423 kernel: audit: type=1334 audit(1734099190.825:94): prog-id=12 op=UNLOAD Dec 13 14:13:11.280452 kernel: audit: type=1334 audit(1734099190.827:95): prog-id=16 op=LOAD Dec 13 14:13:11.280481 kernel: audit: type=1334 audit(1734099190.829:96): prog-id=17 op=LOAD Dec 13 14:13:11.280520 systemd[1]: initrd-switch-root.service: Deactivated successfully. Dec 13 14:13:11.280554 systemd[1]: Stopped initrd-switch-root.service. Dec 13 14:13:11.280586 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Dec 13 14:13:11.280620 systemd[1]: Created slice system-addon\x2dconfig.slice. Dec 13 14:13:11.280654 systemd[1]: Created slice system-addon\x2drun.slice. Dec 13 14:13:11.280686 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice. Dec 13 14:13:11.280716 systemd[1]: Created slice system-getty.slice. Dec 13 14:13:11.280745 systemd[1]: Created slice system-modprobe.slice. Dec 13 14:13:11.280775 systemd[1]: Created slice system-serial\x2dgetty.slice. Dec 13 14:13:11.280807 systemd[1]: Created slice system-system\x2dcloudinit.slice. Dec 13 14:13:11.280842 systemd[1]: Created slice system-systemd\x2dfsck.slice. Dec 13 14:13:11.280890 systemd[1]: Created slice user.slice. Dec 13 14:13:11.280923 systemd[1]: Started systemd-ask-password-console.path. Dec 13 14:13:11.280959 systemd[1]: Started systemd-ask-password-wall.path. Dec 13 14:13:11.288600 systemd[1]: Set up automount boot.automount. Dec 13 14:13:11.288711 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount. Dec 13 14:13:11.288743 systemd[1]: Stopped target initrd-switch-root.target. Dec 13 14:13:11.288779 systemd[1]: Stopped target initrd-fs.target. Dec 13 14:13:11.288810 systemd[1]: Stopped target initrd-root-fs.target. Dec 13 14:13:11.288848 systemd[1]: Reached target integritysetup.target. Dec 13 14:13:11.288918 systemd[1]: Reached target remote-cryptsetup.target. Dec 13 14:13:11.288954 systemd[1]: Reached target remote-fs.target. Dec 13 14:13:11.288985 systemd[1]: Reached target slices.target. Dec 13 14:13:11.289015 systemd[1]: Reached target swap.target. Dec 13 14:13:11.289045 systemd[1]: Reached target torcx.target. Dec 13 14:13:11.289077 systemd[1]: Reached target veritysetup.target. Dec 13 14:13:11.289107 systemd[1]: Listening on systemd-coredump.socket. Dec 13 14:13:11.289139 systemd[1]: Listening on systemd-initctl.socket. Dec 13 14:13:11.289171 systemd[1]: Listening on systemd-networkd.socket. Dec 13 14:13:11.289206 systemd[1]: Listening on systemd-udevd-control.socket. Dec 13 14:13:11.289236 systemd[1]: Listening on systemd-udevd-kernel.socket. Dec 13 14:13:11.289365 systemd[1]: Listening on systemd-userdbd.socket. Dec 13 14:13:11.289402 systemd[1]: Mounting dev-hugepages.mount... Dec 13 14:13:11.289433 systemd[1]: Mounting dev-mqueue.mount... Dec 13 14:13:11.289464 systemd[1]: Mounting media.mount... Dec 13 14:13:11.289496 systemd[1]: Mounting sys-kernel-debug.mount... Dec 13 14:13:11.289527 systemd[1]: Mounting sys-kernel-tracing.mount... Dec 13 14:13:11.289557 systemd[1]: Mounting tmp.mount... Dec 13 14:13:11.289591 systemd[1]: Starting flatcar-tmpfiles.service... Dec 13 14:13:11.289621 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Dec 13 14:13:11.289655 systemd[1]: Starting kmod-static-nodes.service... Dec 13 14:13:11.289686 systemd[1]: Starting modprobe@configfs.service... Dec 13 14:13:11.289718 systemd[1]: Starting modprobe@dm_mod.service... Dec 13 14:13:11.289749 systemd[1]: Starting modprobe@drm.service... Dec 13 14:13:11.289782 systemd[1]: Starting modprobe@efi_pstore.service... Dec 13 14:13:11.289813 systemd[1]: Starting modprobe@fuse.service... Dec 13 14:13:11.289843 systemd[1]: Starting modprobe@loop.service... Dec 13 14:13:11.289943 systemd[1]: setup-nsswitch.service was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Dec 13 14:13:11.289978 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Dec 13 14:13:11.290012 systemd[1]: Stopped systemd-fsck-root.service. Dec 13 14:13:11.290042 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Dec 13 14:13:11.290073 systemd[1]: Stopped systemd-fsck-usr.service. Dec 13 14:13:11.290104 systemd[1]: Stopped systemd-journald.service. Dec 13 14:13:11.290137 systemd[1]: Starting systemd-journald.service... Dec 13 14:13:11.290166 systemd[1]: Starting systemd-modules-load.service... Dec 13 14:13:11.290199 systemd[1]: Starting systemd-network-generator.service... Dec 13 14:13:11.290235 kernel: fuse: init (API version 7.34) Dec 13 14:13:11.290266 systemd[1]: Starting systemd-remount-fs.service... Dec 13 14:13:11.290297 systemd[1]: Starting systemd-udev-trigger.service... Dec 13 14:13:11.290329 systemd[1]: verity-setup.service: Deactivated successfully. Dec 13 14:13:11.290359 systemd[1]: Stopped verity-setup.service. Dec 13 14:13:11.290388 systemd[1]: Mounted dev-hugepages.mount. Dec 13 14:13:11.290419 kernel: loop: module loaded Dec 13 14:13:11.290447 systemd[1]: Mounted dev-mqueue.mount. Dec 13 14:13:11.290476 systemd[1]: Mounted media.mount. Dec 13 14:13:11.290511 systemd[1]: Mounted sys-kernel-debug.mount. Dec 13 14:13:11.290545 systemd[1]: Mounted sys-kernel-tracing.mount. Dec 13 14:13:11.290575 systemd[1]: Mounted tmp.mount. Dec 13 14:13:11.290606 systemd[1]: Finished kmod-static-nodes.service. Dec 13 14:13:11.290636 systemd[1]: modprobe@configfs.service: Deactivated successfully. Dec 13 14:13:11.290689 systemd[1]: Finished modprobe@configfs.service. Dec 13 14:13:11.290722 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Dec 13 14:13:11.290758 systemd[1]: Finished modprobe@dm_mod.service. Dec 13 14:13:11.290789 systemd[1]: modprobe@drm.service: Deactivated successfully. Dec 13 14:13:11.290819 systemd[1]: Finished modprobe@drm.service. Dec 13 14:13:11.290850 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Dec 13 14:13:11.290905 systemd[1]: Finished modprobe@efi_pstore.service. Dec 13 14:13:11.290940 systemd[1]: modprobe@fuse.service: Deactivated successfully. Dec 13 14:13:11.290970 systemd[1]: Finished modprobe@fuse.service. Dec 13 14:13:11.291004 systemd[1]: modprobe@loop.service: Deactivated successfully. Dec 13 14:13:11.291035 systemd[1]: Finished modprobe@loop.service. Dec 13 14:13:11.291066 systemd[1]: Finished systemd-modules-load.service. Dec 13 14:13:11.291098 systemd[1]: Finished systemd-network-generator.service. Dec 13 14:13:11.291128 systemd[1]: Finished systemd-remount-fs.service. Dec 13 14:13:11.291161 systemd[1]: Reached target network-pre.target. Dec 13 14:13:11.291192 systemd[1]: Mounting sys-fs-fuse-connections.mount... Dec 13 14:13:11.291222 systemd[1]: Mounting sys-kernel-config.mount... Dec 13 14:13:11.291252 systemd[1]: remount-root.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Dec 13 14:13:11.291283 systemd[1]: Starting systemd-hwdb-update.service... Dec 13 14:13:11.291313 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Dec 13 14:13:11.291342 systemd[1]: Starting systemd-random-seed.service... Dec 13 14:13:11.291375 systemd-journald[1489]: Journal started Dec 13 14:13:11.291488 systemd-journald[1489]: Runtime Journal (/run/log/journal/ec27bc6a5f32b41c3b9acc277702825f) is 8.0M, max 75.4M, 67.4M free. Dec 13 14:13:05.430000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Dec 13 14:13:05.702000 audit[1]: AVC avc: denied { bpf } for pid=1 comm="systemd" capability=39 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Dec 13 14:13:05.702000 audit[1]: AVC avc: denied { perfmon } for pid=1 comm="systemd" capability=38 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Dec 13 14:13:05.702000 audit: BPF prog-id=10 op=LOAD Dec 13 14:13:05.702000 audit: BPF prog-id=10 op=UNLOAD Dec 13 14:13:05.703000 audit: BPF prog-id=11 op=LOAD Dec 13 14:13:05.703000 audit: BPF prog-id=11 op=UNLOAD Dec 13 14:13:05.837000 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" Dec 13 14:13:05.837000 audit[1410]: SYSCALL arch=c00000b7 syscall=5 success=yes exit=0 a0=400014d8ac a1=40000d0de0 a2=40000d70c0 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) Dec 13 14:13:05.837000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Dec 13 14:13:05.841000 audit[1410]: AVC avc: denied { associate } for pid=1410 comm="torcx-generator" name="lib" scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Dec 13 14:13:05.841000 audit[1410]: SYSCALL arch=c00000b7 syscall=34 success=yes exit=0 a0=ffffffffffffff9c a1=400014d985 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) Dec 13 14:13:05.841000 audit: CWD cwd="/" Dec 13 14:13:05.841000 audit: PATH item=0 name=(null) inode=2 dev=00:1c mode=040755 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:unlabeled_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Dec 13 14:13:05.841000 audit: PATH item=1 name=(null) inode=3 dev=00:1c mode=040755 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:unlabeled_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Dec 13 14:13:05.841000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Dec 13 14:13:10.820000 audit: BPF prog-id=12 op=LOAD Dec 13 14:13:10.820000 audit: BPF prog-id=3 op=UNLOAD Dec 13 14:13:10.820000 audit: BPF prog-id=13 op=LOAD Dec 13 14:13:10.820000 audit: BPF prog-id=14 op=LOAD Dec 13 14:13:10.821000 audit: BPF prog-id=4 op=UNLOAD Dec 13 14:13:10.821000 audit: BPF prog-id=5 op=UNLOAD Dec 13 14:13:10.825000 audit: BPF prog-id=15 op=LOAD Dec 13 14:13:10.825000 audit: BPF prog-id=12 op=UNLOAD Dec 13 14:13:10.827000 audit: BPF prog-id=16 op=LOAD Dec 13 14:13:11.302591 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Dec 13 14:13:10.829000 audit: BPF prog-id=17 op=LOAD Dec 13 14:13:10.829000 audit: BPF prog-id=13 op=UNLOAD Dec 13 14:13:10.829000 audit: BPF prog-id=14 op=UNLOAD Dec 13 14:13:10.832000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:10.840000 audit: BPF prog-id=15 op=UNLOAD Dec 13 14:13:10.850000 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' Dec 13 14:13:10.857000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:10.857000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:11.083000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:11.089000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck-usr comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:11.092000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:11.092000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:11.094000 audit: BPF prog-id=18 op=LOAD Dec 13 14:13:11.094000 audit: BPF prog-id=19 op=LOAD Dec 13 14:13:11.094000 audit: BPF prog-id=20 op=LOAD Dec 13 14:13:11.094000 audit: BPF prog-id=16 op=UNLOAD Dec 13 14:13:11.094000 audit: BPF prog-id=17 op=UNLOAD Dec 13 14:13:11.140000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=verity-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:11.173000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:11.183000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@configfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:11.183000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@configfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:11.195000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:11.195000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:11.201000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:11.201000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:11.209000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:11.209000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:11.221000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@fuse comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:11.221000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@fuse comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:11.310994 systemd[1]: Starting systemd-sysctl.service... Dec 13 14:13:11.227000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:11.227000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:11.232000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:11.237000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-network-generator comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:11.244000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-remount-fs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:11.274000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Dec 13 14:13:11.274000 audit[1489]: SYSCALL arch=c00000b7 syscall=211 success=yes exit=60 a0=6 a1=fffffc25b020 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) Dec 13 14:13:11.274000 audit: PROCTITLE proctitle="/usr/lib/systemd/systemd-journald" Dec 13 14:13:05.834350 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2024-12-13T14:13:05Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3510.3.6 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.6 /var/lib/torcx/store]" Dec 13 14:13:10.820067 systemd[1]: Queued start job for default target multi-user.target. Dec 13 14:13:05.835432 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2024-12-13T14:13:05Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Dec 13 14:13:10.833911 systemd[1]: systemd-journald.service: Deactivated successfully. Dec 13 14:13:05.835481 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2024-12-13T14:13:05Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Dec 13 14:13:05.835547 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2024-12-13T14:13:05Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Dec 13 14:13:05.835573 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2024-12-13T14:13:05Z" level=debug msg="skipped missing lower profile" missing profile=oem Dec 13 14:13:05.835632 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2024-12-13T14:13:05Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Dec 13 14:13:11.321837 systemd[1]: Started systemd-journald.service. Dec 13 14:13:11.313000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:05.835663 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2024-12-13T14:13:05Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Dec 13 14:13:11.315696 systemd[1]: Mounted sys-fs-fuse-connections.mount. Dec 13 14:13:05.836093 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2024-12-13T14:13:05Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Dec 13 14:13:11.320262 systemd[1]: Mounted sys-kernel-config.mount. Dec 13 14:13:05.836171 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2024-12-13T14:13:05Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Dec 13 14:13:05.836206 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2024-12-13T14:13:05Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Dec 13 14:13:05.838258 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2024-12-13T14:13:05Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:20.10.torcx.tgz" reference=20.10 Dec 13 14:13:05.838342 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2024-12-13T14:13:05Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:com.coreos.cl.torcx.tgz" reference=com.coreos.cl Dec 13 14:13:05.838387 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2024-12-13T14:13:05Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3510.3.6: no such file or directory" path=/usr/share/oem/torcx/store/3510.3.6 Dec 13 14:13:05.838426 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2024-12-13T14:13:05Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Dec 13 14:13:05.838476 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2024-12-13T14:13:05Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3510.3.6: no such file or directory" path=/var/lib/torcx/store/3510.3.6 Dec 13 14:13:05.838554 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2024-12-13T14:13:05Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Dec 13 14:13:09.991420 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2024-12-13T14:13:09Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Dec 13 14:13:11.324377 systemd[1]: Starting systemd-journal-flush.service... Dec 13 14:13:09.991972 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2024-12-13T14:13:09Z" level=debug msg="binaries propagated" assets="[/bin/containerd /bin/containerd-shim /bin/ctr /bin/docker /bin/docker-containerd /bin/docker-containerd-shim /bin/docker-init /bin/docker-proxy /bin/docker-runc /bin/dockerd /bin/runc /bin/tini]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Dec 13 14:13:09.992218 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2024-12-13T14:13:09Z" level=debug msg="networkd units propagated" assets="[/lib/systemd/network/50-docker.network /lib/systemd/network/90-docker-veth.network]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Dec 13 14:13:09.992667 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2024-12-13T14:13:09Z" level=debug msg="systemd units propagated" assets="[/lib/systemd/system/containerd.service /lib/systemd/system/docker.service /lib/systemd/system/docker.socket /lib/systemd/system/sockets.target.wants /lib/systemd/system/multi-user.target.wants]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Dec 13 14:13:09.992778 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2024-12-13T14:13:09Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Dec 13 14:13:09.992933 /usr/lib/systemd/system-generators/torcx-generator[1410]: time="2024-12-13T14:13:09Z" level=debug msg="system state sealed" content="[TORCX_LOWER_PROFILES=\"vendor\" TORCX_UPPER_PROFILE=\"\" TORCX_PROFILE_PATH=\"/run/torcx/profile.json\" TORCX_BINDIR=\"/run/torcx/bin\" TORCX_UNPACKDIR=\"/run/torcx/unpack\"]" path=/run/metadata/torcx Dec 13 14:13:11.344000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=flatcar-tmpfiles comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:11.344218 systemd[1]: Finished flatcar-tmpfiles.service. Dec 13 14:13:11.348634 systemd[1]: Starting systemd-sysusers.service... Dec 13 14:13:11.367975 systemd-journald[1489]: Time spent on flushing to /var/log/journal/ec27bc6a5f32b41c3b9acc277702825f is 70.516ms for 1117 entries. Dec 13 14:13:11.367975 systemd-journald[1489]: System Journal (/var/log/journal/ec27bc6a5f32b41c3b9acc277702825f) is 8.0M, max 195.6M, 187.6M free. Dec 13 14:13:11.467561 systemd-journald[1489]: Received client request to flush runtime journal. Dec 13 14:13:11.372000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-random-seed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:11.386000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:11.423000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:11.435000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-sysusers comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:11.372353 systemd[1]: Finished systemd-random-seed.service. Dec 13 14:13:11.374292 systemd[1]: Reached target first-boot-complete.target. Dec 13 14:13:11.468750 udevadm[1529]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Dec 13 14:13:11.386630 systemd[1]: Finished systemd-sysctl.service. Dec 13 14:13:11.469000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journal-flush comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:11.422552 systemd[1]: Finished systemd-udev-trigger.service. Dec 13 14:13:11.426550 systemd[1]: Starting systemd-udev-settle.service... Dec 13 14:13:11.436092 systemd[1]: Finished systemd-sysusers.service. Dec 13 14:13:11.469237 systemd[1]: Finished systemd-journal-flush.service. Dec 13 14:13:12.183000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-hwdb-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:12.184000 audit: BPF prog-id=21 op=LOAD Dec 13 14:13:12.184000 audit: BPF prog-id=22 op=LOAD Dec 13 14:13:12.184000 audit: BPF prog-id=7 op=UNLOAD Dec 13 14:13:12.184000 audit: BPF prog-id=8 op=UNLOAD Dec 13 14:13:12.183740 systemd[1]: Finished systemd-hwdb-update.service. Dec 13 14:13:12.187706 systemd[1]: Starting systemd-udevd.service... Dec 13 14:13:12.224793 systemd-udevd[1531]: Using default interface naming scheme 'v252'. Dec 13 14:13:12.273567 systemd[1]: Started systemd-udevd.service. Dec 13 14:13:12.273000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:12.275000 audit: BPF prog-id=23 op=LOAD Dec 13 14:13:12.278224 systemd[1]: Starting systemd-networkd.service... Dec 13 14:13:12.285000 audit: BPF prog-id=24 op=LOAD Dec 13 14:13:12.285000 audit: BPF prog-id=25 op=LOAD Dec 13 14:13:12.285000 audit: BPF prog-id=26 op=LOAD Dec 13 14:13:12.288660 systemd[1]: Starting systemd-userdbd.service... Dec 13 14:13:12.382761 systemd[1]: Started systemd-userdbd.service. Dec 13 14:13:12.382000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-userdbd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:12.386660 systemd[1]: Condition check resulted in dev-ttyS0.device being skipped. Dec 13 14:13:12.403810 (udev-worker)[1546]: Network interface NamePolicy= disabled on kernel command line. Dec 13 14:13:12.515850 systemd-networkd[1535]: lo: Link UP Dec 13 14:13:12.515892 systemd-networkd[1535]: lo: Gained carrier Dec 13 14:13:12.516810 systemd-networkd[1535]: Enumeration completed Dec 13 14:13:12.516989 systemd[1]: Started systemd-networkd.service. Dec 13 14:13:12.517000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:12.520880 systemd[1]: Starting systemd-networkd-wait-online.service... Dec 13 14:13:12.521801 systemd-networkd[1535]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Dec 13 14:13:12.527918 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Dec 13 14:13:12.528548 systemd-networkd[1535]: eth0: Link UP Dec 13 14:13:12.528856 systemd-networkd[1535]: eth0: Gained carrier Dec 13 14:13:12.543142 systemd-networkd[1535]: eth0: DHCPv4 address 172.31.23.153/20, gateway 172.31.16.1 acquired from 172.31.16.1 Dec 13 14:13:12.587926 kernel: BTRFS info: devid 1 device path /dev/disk/by-label/OEM changed to /dev/nvme0n1p6 scanned by (udev-worker) (1561) Dec 13 14:13:12.729551 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Dec 13 14:13:12.732076 systemd[1]: Finished systemd-udev-settle.service. Dec 13 14:13:12.732000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udev-settle comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:12.736135 systemd[1]: Starting lvm2-activation-early.service... Dec 13 14:13:12.774378 lvm[1650]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Dec 13 14:13:12.809466 systemd[1]: Finished lvm2-activation-early.service. Dec 13 14:13:12.810000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=lvm2-activation-early comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:12.811592 systemd[1]: Reached target cryptsetup.target. Dec 13 14:13:12.815517 systemd[1]: Starting lvm2-activation.service... Dec 13 14:13:12.824092 lvm[1651]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Dec 13 14:13:12.862650 systemd[1]: Finished lvm2-activation.service. Dec 13 14:13:12.864488 systemd[1]: Reached target local-fs-pre.target. Dec 13 14:13:12.863000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=lvm2-activation comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:12.866167 systemd[1]: var-lib-machines.mount was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Dec 13 14:13:12.866352 systemd[1]: Reached target local-fs.target. Dec 13 14:13:12.868091 systemd[1]: Reached target machines.target. Dec 13 14:13:12.872112 systemd[1]: Starting ldconfig.service... Dec 13 14:13:12.874929 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Dec 13 14:13:12.875275 systemd[1]: systemd-boot-system-token.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/LoaderFeatures-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Dec 13 14:13:12.877929 systemd[1]: Starting systemd-boot-update.service... Dec 13 14:13:12.882021 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service... Dec 13 14:13:12.887728 systemd[1]: Starting systemd-machine-id-commit.service... Dec 13 14:13:12.889636 systemd[1]: systemd-sysext.service was skipped because no trigger condition checks were met. Dec 13 14:13:12.889733 systemd[1]: ensure-sysext.service was skipped because no trigger condition checks were met. Dec 13 14:13:12.894634 systemd[1]: Starting systemd-tmpfiles-setup.service... Dec 13 14:13:12.911925 systemd[1]: boot.automount: Got automount request for /boot, triggered by 1653 (bootctl) Dec 13 14:13:12.914229 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service... Dec 13 14:13:12.937168 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service. Dec 13 14:13:12.935000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck@dev-disk-by\x2dlabel-OEM comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:12.946832 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Dec 13 14:13:12.946000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-machine-id-commit comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:12.947916 systemd[1]: Finished systemd-machine-id-commit.service. Dec 13 14:13:12.959634 systemd-tmpfiles[1656]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Dec 13 14:13:12.967472 systemd-tmpfiles[1656]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Dec 13 14:13:12.979195 systemd-tmpfiles[1656]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Dec 13 14:13:13.029001 systemd-fsck[1661]: fsck.fat 4.2 (2021-01-31) Dec 13 14:13:13.029001 systemd-fsck[1661]: /dev/nvme0n1p1: 236 files, 117175/258078 clusters Dec 13 14:13:13.032595 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service. Dec 13 14:13:13.033000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:13.037446 systemd[1]: Mounting boot.mount... Dec 13 14:13:13.060355 systemd[1]: Mounted boot.mount. Dec 13 14:13:13.089986 systemd[1]: Finished systemd-boot-update.service. Dec 13 14:13:13.092000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-boot-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:13.317116 systemd[1]: Finished systemd-tmpfiles-setup.service. Dec 13 14:13:13.317000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:13.321358 systemd[1]: Starting audit-rules.service... Dec 13 14:13:13.325815 systemd[1]: Starting clean-ca-certificates.service... Dec 13 14:13:13.333955 systemd[1]: Starting systemd-journal-catalog-update.service... Dec 13 14:13:13.335000 audit: BPF prog-id=27 op=LOAD Dec 13 14:13:13.342000 audit: BPF prog-id=28 op=LOAD Dec 13 14:13:13.341252 systemd[1]: Starting systemd-resolved.service... Dec 13 14:13:13.348279 systemd[1]: Starting systemd-timesyncd.service... Dec 13 14:13:13.353347 systemd[1]: Starting systemd-update-utmp.service... Dec 13 14:13:13.374000 audit[1682]: SYSTEM_BOOT pid=1682 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg=' comm="systemd-update-utmp" exe="/usr/lib/systemd/systemd-update-utmp" hostname=? addr=? terminal=? res=success' Dec 13 14:13:13.381000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=clean-ca-certificates comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:13.380402 systemd[1]: Finished clean-ca-certificates.service. Dec 13 14:13:13.386000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-update-utmp comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:13.382541 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Dec 13 14:13:13.385626 systemd[1]: Finished systemd-update-utmp.service. Dec 13 14:13:13.440000 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' Dec 13 14:13:13.440098 systemd[1]: Finished systemd-journal-catalog-update.service. Dec 13 14:13:13.486000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Dec 13 14:13:13.486000 audit[1696]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffc45d6640 a2=420 a3=0 items=0 ppid=1675 pid=1696 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditctl" exe="/usr/sbin/auditctl" subj=system_u:system_r:kernel_t:s0 key=(null) Dec 13 14:13:13.486000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573 Dec 13 14:13:13.488788 augenrules[1696]: No rules Dec 13 14:13:13.490706 systemd[1]: Finished audit-rules.service. Dec 13 14:13:13.517315 systemd[1]: Started systemd-timesyncd.service. Dec 13 14:13:13.519215 systemd[1]: Reached target time-set.target. Dec 13 14:13:13.543462 systemd-resolved[1679]: Positive Trust Anchors: Dec 13 14:13:13.544112 systemd-resolved[1679]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Dec 13 14:13:13.544277 systemd-resolved[1679]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Dec 13 14:13:13.573036 systemd-resolved[1679]: Defaulting to hostname 'linux'. Dec 13 14:13:13.576109 systemd[1]: Started systemd-resolved.service. Dec 13 14:13:13.577852 systemd[1]: Reached target network.target. Dec 13 14:13:13.579389 systemd[1]: Reached target nss-lookup.target. Dec 13 14:13:13.598162 ldconfig[1652]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Dec 13 14:13:13.605105 systemd[1]: Finished ldconfig.service. Dec 13 14:13:13.609025 systemd[1]: Starting systemd-update-done.service... Dec 13 14:13:13.623324 systemd[1]: Finished systemd-update-done.service. Dec 13 14:13:13.625262 systemd[1]: Reached target sysinit.target. Dec 13 14:13:13.626983 systemd[1]: Started motdgen.path. Dec 13 14:13:13.628432 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path. Dec 13 14:13:13.630840 systemd[1]: Started logrotate.timer. Dec 13 14:13:13.632427 systemd[1]: Started mdadm.timer. Dec 13 14:13:13.633778 systemd[1]: Started systemd-tmpfiles-clean.timer. Dec 13 14:13:13.635479 systemd[1]: update-engine-stub.timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Dec 13 14:13:13.635526 systemd[1]: Reached target paths.target. Dec 13 14:13:13.636946 systemd[1]: Reached target timers.target. Dec 13 14:13:13.639509 systemd[1]: Listening on dbus.socket. Dec 13 14:13:13.643173 systemd[1]: Starting docker.socket... Dec 13 14:13:13.649430 systemd[1]: Listening on sshd.socket. Dec 13 14:13:13.651309 systemd[1]: systemd-pcrphase-sysinit.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Dec 13 14:13:13.652299 systemd[1]: Listening on docker.socket. Dec 13 14:13:13.654122 systemd[1]: Reached target sockets.target. Dec 13 14:13:13.655989 systemd[1]: Reached target basic.target. Dec 13 14:13:13.657579 systemd[1]: addon-config@usr-share-oem.service was skipped because no trigger condition checks were met. Dec 13 14:13:13.657762 systemd[1]: addon-run@usr-share-oem.service was skipped because no trigger condition checks were met. Dec 13 14:13:13.670835 systemd[1]: Starting containerd.service... Dec 13 14:13:13.674684 systemd[1]: Starting coreos-metadata-sshkeys@core.service... Dec 13 14:13:13.679098 systemd[1]: Starting coreos-metadata.service... Dec 13 14:13:13.683285 systemd[1]: Starting dbus.service... Dec 13 14:13:13.689311 systemd[1]: Starting enable-oem-cloudinit.service... Dec 13 14:13:13.693591 systemd[1]: Starting extend-filesystems.service... Dec 13 14:13:13.695250 systemd[1]: flatcar-setup-environment.service was skipped because of an unmet condition check (ConditionPathExists=/usr/share/oem/bin/flatcar-setup-environment). Dec 13 14:13:13.697677 systemd[1]: Starting motdgen.service... Dec 13 14:13:13.702846 systemd[1]: Starting ssh-key-proc-cmdline.service... Dec 13 14:13:13.706711 systemd[1]: Starting sshd-keygen.service... Dec 13 14:13:13.713207 systemd[1]: Starting systemd-logind.service... Dec 13 14:13:13.714943 systemd[1]: systemd-pcrphase.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Dec 13 14:13:13.715070 systemd[1]: tcsd.service was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Dec 13 14:13:13.750421 jq[1718]: true Dec 13 14:13:13.716296 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Dec 13 14:13:13.754682 jq[1709]: false Dec 13 14:13:13.718554 systemd[1]: Starting update-engine.service... Dec 13 14:13:13.722851 systemd[1]: Starting update-ssh-keys-after-ignition.service... Dec 13 14:13:13.759392 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Dec 13 14:13:13.759767 systemd[1]: Condition check resulted in enable-oem-cloudinit.service being skipped. Dec 13 14:13:13.762847 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Dec 13 14:13:13.763231 systemd[1]: Finished ssh-key-proc-cmdline.service. Dec 13 14:13:13.796103 jq[1728]: true Dec 13 14:13:13.826970 dbus-daemon[1708]: [system] SELinux support is enabled Dec 13 14:13:13.827852 systemd[1]: Started dbus.service. Dec 13 14:13:13.832999 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Dec 13 14:13:13.833058 systemd[1]: Reached target system-config.target. Dec 13 14:13:13.835031 systemd[1]: user-cloudinit-proc-cmdline.service was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Dec 13 14:13:13.835081 systemd[1]: Reached target user-config.target. Dec 13 14:13:13.848334 dbus-daemon[1708]: [system] Activating systemd to hand-off: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.1' (uid=244 pid=1535 comm="/usr/lib/systemd/systemd-networkd" label="system_u:system_r:kernel_t:s0") Dec 13 14:13:13.851432 dbus-daemon[1708]: [system] Successfully activated service 'org.freedesktop.systemd1' Dec 13 14:13:13.860104 systemd[1]: Starting systemd-hostnamed.service... Dec 13 14:13:13.871810 systemd[1]: motdgen.service: Deactivated successfully. Dec 13 14:13:13.872207 systemd[1]: Finished motdgen.service. Dec 13 14:13:13.890878 extend-filesystems[1710]: Found nvme0n1 Dec 13 14:13:13.890878 extend-filesystems[1710]: Found nvme0n1p1 Dec 13 14:13:13.890878 extend-filesystems[1710]: Found nvme0n1p2 Dec 13 14:13:13.890878 extend-filesystems[1710]: Found nvme0n1p3 Dec 13 14:13:13.890878 extend-filesystems[1710]: Found usr Dec 13 14:13:13.890878 extend-filesystems[1710]: Found nvme0n1p4 Dec 13 14:13:13.890878 extend-filesystems[1710]: Found nvme0n1p6 Dec 13 14:13:13.890878 extend-filesystems[1710]: Found nvme0n1p7 Dec 13 14:13:13.890878 extend-filesystems[1710]: Found nvme0n1p9 Dec 13 14:13:13.890878 extend-filesystems[1710]: Checking size of /dev/nvme0n1p9 Dec 13 14:13:13.905092 systemd-networkd[1535]: eth0: Gained IPv6LL Dec 13 14:13:13.906115 systemd-timesyncd[1680]: Network configuration changed, trying to establish connection. Dec 13 14:13:13.913568 systemd[1]: Finished systemd-networkd-wait-online.service. Dec 13 14:13:13.915666 systemd[1]: Reached target network-online.target. Dec 13 14:13:13.919535 systemd[1]: Started amazon-ssm-agent.service. Dec 13 14:13:13.923853 systemd[1]: Started nvidia.service. Dec 13 14:13:14.020365 update_engine[1716]: I1213 14:13:14.019670 1716 main.cc:92] Flatcar Update Engine starting Dec 13 14:13:14.026132 extend-filesystems[1710]: Resized partition /dev/nvme0n1p9 Dec 13 14:13:14.032005 systemd[1]: Started update-engine.service. Dec 13 14:13:14.037072 systemd[1]: Started locksmithd.service. Dec 13 14:13:14.040372 update_engine[1716]: I1213 14:13:14.040311 1716 update_check_scheduler.cc:74] Next update check in 9m5s Dec 13 14:13:14.058797 extend-filesystems[1769]: resize2fs 1.46.5 (30-Dec-2021) Dec 13 14:13:14.073346 bash[1762]: Updated "/home/core/.ssh/authorized_keys" Dec 13 14:13:14.074894 systemd[1]: Finished update-ssh-keys-after-ignition.service. Dec 13 14:13:14.134914 kernel: EXT4-fs (nvme0n1p9): resizing filesystem from 553472 to 1489915 blocks Dec 13 14:13:14.155117 env[1726]: time="2024-12-13T14:13:14.155036134Z" level=info msg="starting containerd" revision=92b3a9d6f1b3bcc6dc74875cfdea653fe39f09c2 version=1.6.16 Dec 13 14:13:14.216894 kernel: EXT4-fs (nvme0n1p9): resized filesystem to 1489915 Dec 13 14:13:14.236968 extend-filesystems[1769]: Filesystem at /dev/nvme0n1p9 is mounted on /; on-line resizing required Dec 13 14:13:14.236968 extend-filesystems[1769]: old_desc_blocks = 1, new_desc_blocks = 1 Dec 13 14:13:14.236968 extend-filesystems[1769]: The filesystem on /dev/nvme0n1p9 is now 1489915 (4k) blocks long. Dec 13 14:13:14.243976 extend-filesystems[1710]: Resized filesystem in /dev/nvme0n1p9 Dec 13 14:13:14.255291 systemd[1]: extend-filesystems.service: Deactivated successfully. Dec 13 14:13:14.255661 systemd[1]: Finished extend-filesystems.service. Dec 13 14:13:14.281406 amazon-ssm-agent[1754]: 2024/12/13 14:13:14 Failed to load instance info from vault. RegistrationKey does not exist. Dec 13 14:13:14.281904 systemd-logind[1715]: Watching system buttons on /dev/input/event0 (Power Button) Dec 13 14:13:14.282404 systemd-logind[1715]: Watching system buttons on /dev/input/event1 (Sleep Button) Dec 13 14:13:14.283134 systemd-logind[1715]: New seat seat0. Dec 13 14:13:14.301897 systemd[1]: Started systemd-logind.service. Dec 13 14:13:14.304921 amazon-ssm-agent[1754]: Initializing new seelog logger Dec 13 14:13:14.305139 amazon-ssm-agent[1754]: New Seelog Logger Creation Complete Dec 13 14:13:14.305252 amazon-ssm-agent[1754]: 2024/12/13 14:13:14 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Dec 13 14:13:14.305252 amazon-ssm-agent[1754]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Dec 13 14:13:14.305565 amazon-ssm-agent[1754]: 2024/12/13 14:13:14 processing appconfig overrides Dec 13 14:13:14.341428 dbus-daemon[1708]: [system] Successfully activated service 'org.freedesktop.hostname1' Dec 13 14:13:14.341684 systemd[1]: Started systemd-hostnamed.service. Dec 13 14:13:14.346335 dbus-daemon[1708]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.6' (uid=0 pid=1745 comm="/usr/lib/systemd/systemd-hostnamed" label="system_u:system_r:kernel_t:s0") Dec 13 14:13:14.351832 systemd[1]: Starting polkit.service... Dec 13 14:13:14.396338 polkitd[1790]: Started polkitd version 121 Dec 13 14:13:14.431908 polkitd[1790]: Loading rules from directory /etc/polkit-1/rules.d Dec 13 14:13:14.432017 polkitd[1790]: Loading rules from directory /usr/share/polkit-1/rules.d Dec 13 14:13:14.436637 polkitd[1790]: Finished loading, compiling and executing 2 rules Dec 13 14:13:14.437425 dbus-daemon[1708]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Dec 13 14:13:14.437706 systemd[1]: Started polkit.service. Dec 13 14:13:14.440185 polkitd[1790]: Acquired the name org.freedesktop.PolicyKit1 on the system bus Dec 13 14:13:14.462779 env[1726]: time="2024-12-13T14:13:14.453227472Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Dec 13 14:13:14.463476 env[1726]: time="2024-12-13T14:13:14.463416972Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Dec 13 14:13:14.467359 env[1726]: time="2024-12-13T14:13:14.467272368Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/5.15.173-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Dec 13 14:13:14.467359 env[1726]: time="2024-12-13T14:13:14.467347896Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Dec 13 14:13:14.467769 env[1726]: time="2024-12-13T14:13:14.467713512Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Dec 13 14:13:14.467849 env[1726]: time="2024-12-13T14:13:14.467762364Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Dec 13 14:13:14.467849 env[1726]: time="2024-12-13T14:13:14.467798796Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" Dec 13 14:13:14.467849 env[1726]: time="2024-12-13T14:13:14.467824104Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Dec 13 14:13:14.468042 env[1726]: time="2024-12-13T14:13:14.468021396Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Dec 13 14:13:14.468553 env[1726]: time="2024-12-13T14:13:14.468493788Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Dec 13 14:13:14.468816 env[1726]: time="2024-12-13T14:13:14.468763128Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Dec 13 14:13:14.468922 env[1726]: time="2024-12-13T14:13:14.468810252Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Dec 13 14:13:14.470570 systemd[1]: nvidia.service: Deactivated successfully. Dec 13 14:13:14.477779 systemd-hostnamed[1745]: Hostname set to (transient) Dec 13 14:13:14.477780 systemd-resolved[1679]: System hostname changed to 'ip-172-31-23-153'. Dec 13 14:13:14.478738 env[1726]: time="2024-12-13T14:13:14.478028604Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" Dec 13 14:13:14.478738 env[1726]: time="2024-12-13T14:13:14.478075896Z" level=info msg="metadata content store policy set" policy=shared Dec 13 14:13:14.489988 env[1726]: time="2024-12-13T14:13:14.489918504Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Dec 13 14:13:14.490194 env[1726]: time="2024-12-13T14:13:14.489993468Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Dec 13 14:13:14.490194 env[1726]: time="2024-12-13T14:13:14.490029996Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Dec 13 14:13:14.490194 env[1726]: time="2024-12-13T14:13:14.490102764Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Dec 13 14:13:14.490194 env[1726]: time="2024-12-13T14:13:14.490146888Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Dec 13 14:13:14.490194 env[1726]: time="2024-12-13T14:13:14.490182072Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Dec 13 14:13:14.490455 env[1726]: time="2024-12-13T14:13:14.490213680Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Dec 13 14:13:14.490791 env[1726]: time="2024-12-13T14:13:14.490729728Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Dec 13 14:13:14.490898 env[1726]: time="2024-12-13T14:13:14.490788300Z" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1 Dec 13 14:13:14.490898 env[1726]: time="2024-12-13T14:13:14.490822212Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Dec 13 14:13:14.490898 env[1726]: time="2024-12-13T14:13:14.490853820Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Dec 13 14:13:14.491073 env[1726]: time="2024-12-13T14:13:14.490907052Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Dec 13 14:13:14.491171 env[1726]: time="2024-12-13T14:13:14.491129568Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Dec 13 14:13:14.491355 env[1726]: time="2024-12-13T14:13:14.491309088Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Dec 13 14:13:14.491760 env[1726]: time="2024-12-13T14:13:14.491716548Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Dec 13 14:13:14.491895 env[1726]: time="2024-12-13T14:13:14.491776536Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Dec 13 14:13:14.491895 env[1726]: time="2024-12-13T14:13:14.491810436Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Dec 13 14:13:14.492309 env[1726]: time="2024-12-13T14:13:14.492169248Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Dec 13 14:13:14.492309 env[1726]: time="2024-12-13T14:13:14.492219060Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Dec 13 14:13:14.492309 env[1726]: time="2024-12-13T14:13:14.492260688Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Dec 13 14:13:14.492309 env[1726]: time="2024-12-13T14:13:14.492290760Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Dec 13 14:13:14.492569 env[1726]: time="2024-12-13T14:13:14.492321504Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Dec 13 14:13:14.492569 env[1726]: time="2024-12-13T14:13:14.492351924Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Dec 13 14:13:14.492569 env[1726]: time="2024-12-13T14:13:14.492384084Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Dec 13 14:13:14.492569 env[1726]: time="2024-12-13T14:13:14.492414324Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Dec 13 14:13:14.492569 env[1726]: time="2024-12-13T14:13:14.492449412Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Dec 13 14:13:14.492824 env[1726]: time="2024-12-13T14:13:14.492724812Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Dec 13 14:13:14.492824 env[1726]: time="2024-12-13T14:13:14.492758964Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Dec 13 14:13:14.492824 env[1726]: time="2024-12-13T14:13:14.492791868Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Dec 13 14:13:14.492993 env[1726]: time="2024-12-13T14:13:14.492830256Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Dec 13 14:13:14.492993 env[1726]: time="2024-12-13T14:13:14.492881880Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1 Dec 13 14:13:14.492993 env[1726]: time="2024-12-13T14:13:14.492916236Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Dec 13 14:13:14.492993 env[1726]: time="2024-12-13T14:13:14.492951540Z" level=error msg="failed to initialize a tracing processor \"otlp\"" error="no OpenTelemetry endpoint: skip plugin" Dec 13 14:13:14.493203 env[1726]: time="2024-12-13T14:13:14.493014780Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Dec 13 14:13:14.505096 env[1726]: time="2024-12-13T14:13:14.504923364Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.6 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Dec 13 14:13:14.505096 env[1726]: time="2024-12-13T14:13:14.505072644Z" level=info msg="Connect containerd service" Dec 13 14:13:14.506913 env[1726]: time="2024-12-13T14:13:14.505138404Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Dec 13 14:13:14.512143 env[1726]: time="2024-12-13T14:13:14.512061504Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Dec 13 14:13:14.512584 env[1726]: time="2024-12-13T14:13:14.512531196Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Dec 13 14:13:14.512678 env[1726]: time="2024-12-13T14:13:14.512641404Z" level=info msg=serving... address=/run/containerd/containerd.sock Dec 13 14:13:14.512842 systemd[1]: Started containerd.service. Dec 13 14:13:14.517062 env[1726]: time="2024-12-13T14:13:14.515735232Z" level=info msg="Start subscribing containerd event" Dec 13 14:13:14.517062 env[1726]: time="2024-12-13T14:13:14.515843160Z" level=info msg="Start recovering state" Dec 13 14:13:14.517062 env[1726]: time="2024-12-13T14:13:14.515979516Z" level=info msg="Start event monitor" Dec 13 14:13:14.517062 env[1726]: time="2024-12-13T14:13:14.516020844Z" level=info msg="Start snapshots syncer" Dec 13 14:13:14.517062 env[1726]: time="2024-12-13T14:13:14.516045456Z" level=info msg="Start cni network conf syncer for default" Dec 13 14:13:14.517062 env[1726]: time="2024-12-13T14:13:14.516065640Z" level=info msg="Start streaming server" Dec 13 14:13:14.519993 env[1726]: time="2024-12-13T14:13:14.519940596Z" level=info msg="containerd successfully booted in 0.389168s" Dec 13 14:13:14.710919 coreos-metadata[1706]: Dec 13 14:13:14.709 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Dec 13 14:13:14.716234 coreos-metadata[1706]: Dec 13 14:13:14.715 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/public-keys: Attempt #1 Dec 13 14:13:14.719344 coreos-metadata[1706]: Dec 13 14:13:14.719 INFO Fetch successful Dec 13 14:13:14.719656 coreos-metadata[1706]: Dec 13 14:13:14.719 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/public-keys/0/openssh-key: Attempt #1 Dec 13 14:13:14.722574 coreos-metadata[1706]: Dec 13 14:13:14.722 INFO Fetch successful Dec 13 14:13:14.724902 unknown[1706]: wrote ssh authorized keys file for user: core Dec 13 14:13:14.762432 coreos-metadata[1707]: Dec 13 14:13:14.759 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Dec 13 14:13:14.763366 coreos-metadata[1707]: Dec 13 14:13:14.763 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/instance-id: Attempt #1 Dec 13 14:13:14.763733 coreos-metadata[1707]: Dec 13 14:13:14.763 INFO Fetch successful Dec 13 14:13:14.764382 coreos-metadata[1707]: Dec 13 14:13:14.763 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/instance-type: Attempt #1 Dec 13 14:13:14.764781 coreos-metadata[1707]: Dec 13 14:13:14.764 INFO Fetch successful Dec 13 14:13:14.766378 coreos-metadata[1707]: Dec 13 14:13:14.764 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/local-ipv4: Attempt #1 Dec 13 14:13:14.766963 coreos-metadata[1707]: Dec 13 14:13:14.766 INFO Fetch successful Dec 13 14:13:14.766963 coreos-metadata[1707]: Dec 13 14:13:14.766 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/public-ipv4: Attempt #1 Dec 13 14:13:14.767852 coreos-metadata[1707]: Dec 13 14:13:14.767 INFO Fetch successful Dec 13 14:13:14.768386 coreos-metadata[1707]: Dec 13 14:13:14.768 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/placement/availability-zone: Attempt #1 Dec 13 14:13:14.769199 coreos-metadata[1707]: Dec 13 14:13:14.768 INFO Fetch successful Dec 13 14:13:14.769491 coreos-metadata[1707]: Dec 13 14:13:14.769 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/hostname: Attempt #1 Dec 13 14:13:14.770237 coreos-metadata[1707]: Dec 13 14:13:14.770 INFO Fetch successful Dec 13 14:13:14.770532 coreos-metadata[1707]: Dec 13 14:13:14.770 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/public-hostname: Attempt #1 Dec 13 14:13:14.776454 update-ssh-keys[1869]: Updated "/home/core/.ssh/authorized_keys" Dec 13 14:13:14.778338 coreos-metadata[1707]: Dec 13 14:13:14.778 INFO Fetch successful Dec 13 14:13:14.778338 coreos-metadata[1707]: Dec 13 14:13:14.778 INFO Fetching http://169.254.169.254/2019-10-01/dynamic/instance-identity/document: Attempt #1 Dec 13 14:13:14.778638 systemd[1]: Finished coreos-metadata-sshkeys@core.service. Dec 13 14:13:14.782946 coreos-metadata[1707]: Dec 13 14:13:14.782 INFO Fetch successful Dec 13 14:13:14.807635 systemd[1]: Finished coreos-metadata.service. Dec 13 14:13:14.812259 systemd[1]: Starting etcd-member.service... Dec 13 14:13:15.011023 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO Create new startup processor Dec 13 14:13:15.016473 systemd[1]: Starting docker.service... Dec 13 14:13:15.020697 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [LongRunningPluginsManager] registered plugins: {} Dec 13 14:13:15.020697 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO Initializing bookkeeping folders Dec 13 14:13:15.020890 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO removing the completed state files Dec 13 14:13:15.020890 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO Initializing bookkeeping folders for long running plugins Dec 13 14:13:15.020890 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO Initializing replies folder for MDS reply requests that couldn't reach the service Dec 13 14:13:15.020890 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO Initializing healthcheck folders for long running plugins Dec 13 14:13:15.020890 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO Initializing locations for inventory plugin Dec 13 14:13:15.021150 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO Initializing default location for custom inventory Dec 13 14:13:15.021150 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO Initializing default location for file inventory Dec 13 14:13:15.021150 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO Initializing default location for role inventory Dec 13 14:13:15.021150 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO Init the cloudwatchlogs publisher Dec 13 14:13:15.021150 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [instanceID=i-09d6333b4f1e77b79] Successfully loaded platform independent plugin aws:updateSsmAgent Dec 13 14:13:15.021150 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [instanceID=i-09d6333b4f1e77b79] Successfully loaded platform independent plugin aws:configureDocker Dec 13 14:13:15.021150 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [instanceID=i-09d6333b4f1e77b79] Successfully loaded platform independent plugin aws:runDockerAction Dec 13 14:13:15.021150 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [instanceID=i-09d6333b4f1e77b79] Successfully loaded platform independent plugin aws:configurePackage Dec 13 14:13:15.021566 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [instanceID=i-09d6333b4f1e77b79] Successfully loaded platform independent plugin aws:runDocument Dec 13 14:13:15.021566 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [instanceID=i-09d6333b4f1e77b79] Successfully loaded platform independent plugin aws:runPowerShellScript Dec 13 14:13:15.021566 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [instanceID=i-09d6333b4f1e77b79] Successfully loaded platform independent plugin aws:refreshAssociation Dec 13 14:13:15.021566 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [instanceID=i-09d6333b4f1e77b79] Successfully loaded platform independent plugin aws:downloadContent Dec 13 14:13:15.021566 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [instanceID=i-09d6333b4f1e77b79] Successfully loaded platform independent plugin aws:softwareInventory Dec 13 14:13:15.021566 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [instanceID=i-09d6333b4f1e77b79] Successfully loaded platform dependent plugin aws:runShellScript Dec 13 14:13:15.021566 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO Starting Agent: amazon-ssm-agent - v2.3.1319.0 Dec 13 14:13:15.021566 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO OS: linux, Arch: arm64 Dec 13 14:13:15.036844 amazon-ssm-agent[1754]: datastore file /var/lib/amazon/ssm/i-09d6333b4f1e77b79/longrunningplugins/datastore/store doesn't exist - no long running plugins to execute Dec 13 14:13:15.136939 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [MessagingDeliveryService] Starting document processing engine... Dec 13 14:13:15.143750 env[1906]: time="2024-12-13T14:13:15.143675147Z" level=info msg="Starting up" Dec 13 14:13:15.146530 env[1906]: time="2024-12-13T14:13:15.146481599Z" level=info msg="parsed scheme: \"unix\"" module=grpc Dec 13 14:13:15.146717 env[1906]: time="2024-12-13T14:13:15.146687027Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc Dec 13 14:13:15.146885 env[1906]: time="2024-12-13T14:13:15.146830343Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/libcontainerd/docker-containerd.sock 0 }] }" module=grpc Dec 13 14:13:15.147003 env[1906]: time="2024-12-13T14:13:15.146967875Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc Dec 13 14:13:15.151115 env[1906]: time="2024-12-13T14:13:15.151068851Z" level=info msg="parsed scheme: \"unix\"" module=grpc Dec 13 14:13:15.151293 env[1906]: time="2024-12-13T14:13:15.151265483Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc Dec 13 14:13:15.151420 env[1906]: time="2024-12-13T14:13:15.151388903Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/libcontainerd/docker-containerd.sock 0 }] }" module=grpc Dec 13 14:13:15.151527 env[1906]: time="2024-12-13T14:13:15.151500071Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc Dec 13 14:13:15.209322 env[1906]: time="2024-12-13T14:13:15.209264039Z" level=info msg="Loading containers: start." Dec 13 14:13:15.231060 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [MessagingDeliveryService] [EngineProcessor] Starting Dec 13 14:13:15.284473 locksmithd[1767]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Dec 13 14:13:15.325403 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [MessagingDeliveryService] [EngineProcessor] Initial processing Dec 13 14:13:15.419939 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [MessagingDeliveryService] Starting message polling Dec 13 14:13:15.474913 kernel: Initializing XFRM netlink socket Dec 13 14:13:15.514693 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [MessagingDeliveryService] Starting send replies to MDS Dec 13 14:13:15.518005 env[1906]: time="2024-12-13T14:13:15.517947313Z" level=info msg="Default bridge (docker0) is assigned with an IP address 172.17.0.0/16. Daemon option --bip can be used to set a preferred IP address" Dec 13 14:13:15.519397 (udev-worker)[1532]: Network interface NamePolicy= disabled on kernel command line. Dec 13 14:13:15.524909 systemd-timesyncd[1680]: Network configuration changed, trying to establish connection. Dec 13 14:13:15.526285 systemd-timesyncd[1680]: Network configuration changed, trying to establish connection. Dec 13 14:13:15.527489 systemd-timesyncd[1680]: Network configuration changed, trying to establish connection. Dec 13 14:13:15.538787 systemd-timesyncd[1680]: Network configuration changed, trying to establish connection. Dec 13 14:13:15.542545 systemd-timesyncd[1680]: Network configuration changed, trying to establish connection. Dec 13 14:13:15.609660 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [instanceID=i-09d6333b4f1e77b79] Starting association polling Dec 13 14:13:15.660164 systemd-networkd[1535]: docker0: Link UP Dec 13 14:13:15.660726 systemd-timesyncd[1680]: Network configuration changed, trying to establish connection. Dec 13 14:13:15.686156 env[1906]: time="2024-12-13T14:13:15.686111498Z" level=info msg="Loading containers: done." Dec 13 14:13:15.705290 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [MessagingDeliveryService] [Association] [EngineProcessor] Starting Dec 13 14:13:15.708111 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck302711684-merged.mount: Deactivated successfully. Dec 13 14:13:15.744142 env[1906]: time="2024-12-13T14:13:15.744042866Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 Dec 13 14:13:15.744779 env[1906]: time="2024-12-13T14:13:15.744749126Z" level=info msg="Docker daemon" commit=112bdf3343 graphdriver(s)=overlay2 version=20.10.23 Dec 13 14:13:15.745143 env[1906]: time="2024-12-13T14:13:15.745116674Z" level=info msg="Daemon has completed initialization" Dec 13 14:13:15.789602 systemd[1]: Started docker.service. Dec 13 14:13:15.800637 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [MessagingDeliveryService] [Association] Launching response handler Dec 13 14:13:15.801281 env[1906]: time="2024-12-13T14:13:15.801206018Z" level=info msg="API listen on /run/docker.sock" Dec 13 14:13:15.803045 etcd-wrapper[1887]: Error response from daemon: No such container: etcd-member Dec 13 14:13:15.847722 etcd-wrapper[2020]: Error: No such container: etcd-member Dec 13 14:13:15.896106 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [MessagingDeliveryService] [Association] [EngineProcessor] Initial processing Dec 13 14:13:15.949723 etcd-wrapper[2037]: Unable to find image 'quay.io/coreos/etcd:v3.5.16' locally Dec 13 14:13:15.991794 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [MessagingDeliveryService] [Association] Initializing association scheduling service Dec 13 14:13:16.087794 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [MessagingDeliveryService] [Association] Association scheduling service initialized Dec 13 14:13:16.183849 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [MessageGatewayService] Starting session document processing engine... Dec 13 14:13:16.280156 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [MessageGatewayService] [EngineProcessor] Starting Dec 13 14:13:16.376701 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [MessageGatewayService] SSM Agent is trying to setup control channel for Session Manager module. Dec 13 14:13:16.473331 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [MessageGatewayService] Setting up websocket for controlchannel for instance: i-09d6333b4f1e77b79, requestId: 355bd0de-4d2f-4079-a330-4a11b384ec88 Dec 13 14:13:16.570222 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [OfflineService] Starting document processing engine... Dec 13 14:13:16.667352 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [OfflineService] [EngineProcessor] Starting Dec 13 14:13:16.764546 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [OfflineService] [EngineProcessor] Initial processing Dec 13 14:13:16.862025 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [OfflineService] Starting message polling Dec 13 14:13:16.959755 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [OfflineService] Starting send replies to MDS Dec 13 14:13:17.057563 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [LongRunningPluginsManager] starting long running plugin manager Dec 13 14:13:17.069657 etcd-wrapper[2037]: v3.5.16: Pulling from coreos/etcd Dec 13 14:13:17.155636 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [LongRunningPluginsManager] there aren't any long running plugin to execute Dec 13 14:13:17.254038 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [HealthCheck] HealthCheck reporting agent health. Dec 13 14:13:17.324534 etcd-wrapper[2037]: d10f96ed9607: Pulling fs layer Dec 13 14:13:17.324847 etcd-wrapper[2037]: 2ae710cd8bfe: Pulling fs layer Dec 13 14:13:17.325077 etcd-wrapper[2037]: d462aa345367: Pulling fs layer Dec 13 14:13:17.325292 etcd-wrapper[2037]: 0f8b424aa0b9: Pulling fs layer Dec 13 14:13:17.325510 etcd-wrapper[2037]: d557676654e5: Pulling fs layer Dec 13 14:13:17.325723 etcd-wrapper[2037]: c8022d07192e: Pulling fs layer Dec 13 14:13:17.325961 etcd-wrapper[2037]: d858cbc252ad: Pulling fs layer Dec 13 14:13:17.326182 etcd-wrapper[2037]: 1069fc2daed1: Pulling fs layer Dec 13 14:13:17.326396 etcd-wrapper[2037]: b40161cd83fc: Pulling fs layer Dec 13 14:13:17.326610 etcd-wrapper[2037]: 5318d93a3a65: Pulling fs layer Dec 13 14:13:17.326841 etcd-wrapper[2037]: 307c1adadb60: Pulling fs layer Dec 13 14:13:17.327081 etcd-wrapper[2037]: f9b3c65df792: Pulling fs layer Dec 13 14:13:17.327295 etcd-wrapper[2037]: 5709b4a82c35: Pulling fs layer Dec 13 14:13:17.327515 etcd-wrapper[2037]: d5372e382b07: Pulling fs layer Dec 13 14:13:17.327726 etcd-wrapper[2037]: 9c3b5253dddc: Pulling fs layer Dec 13 14:13:17.327955 etcd-wrapper[2037]: 7a57323f7551: Pulling fs layer Dec 13 14:13:17.328158 etcd-wrapper[2037]: 0f8b424aa0b9: Waiting Dec 13 14:13:17.328379 etcd-wrapper[2037]: d557676654e5: Waiting Dec 13 14:13:17.328592 etcd-wrapper[2037]: c8022d07192e: Waiting Dec 13 14:13:17.329488 etcd-wrapper[2037]: d858cbc252ad: Waiting Dec 13 14:13:17.330588 etcd-wrapper[2037]: 1069fc2daed1: Waiting Dec 13 14:13:17.330947 etcd-wrapper[2037]: b40161cd83fc: Waiting Dec 13 14:13:17.331167 etcd-wrapper[2037]: 5318d93a3a65: Waiting Dec 13 14:13:17.332006 etcd-wrapper[2037]: 307c1adadb60: Waiting Dec 13 14:13:17.332335 etcd-wrapper[2037]: f9b3c65df792: Waiting Dec 13 14:13:17.332569 etcd-wrapper[2037]: 5709b4a82c35: Waiting Dec 13 14:13:17.332875 etcd-wrapper[2037]: d5372e382b07: Waiting Dec 13 14:13:17.333140 etcd-wrapper[2037]: 9c3b5253dddc: Waiting Dec 13 14:13:17.333361 etcd-wrapper[2037]: 7a57323f7551: Waiting Dec 13 14:13:17.352437 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [MessageGatewayService] listening reply. Dec 13 14:13:17.451094 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [LongRunningPluginsManager] There are no long running plugins currently getting executed - skipping their healthcheck Dec 13 14:13:17.550021 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [StartupProcessor] Executing startup processor tasks Dec 13 14:13:17.579673 etcd-wrapper[2037]: 2ae710cd8bfe: Verifying Checksum Dec 13 14:13:17.580044 etcd-wrapper[2037]: 2ae710cd8bfe: Download complete Dec 13 14:13:17.649041 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [StartupProcessor] Write to serial port: Amazon SSM Agent v2.3.1319.0 is running Dec 13 14:13:17.748272 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [StartupProcessor] Write to serial port: OsProductName: Flatcar Container Linux by Kinvolk Dec 13 14:13:17.847749 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [StartupProcessor] Write to serial port: OsVersion: 3510.3.6 Dec 13 14:13:17.947305 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [MessageGatewayService] Opening websocket connection to: wss://ssmmessages.us-west-2.amazonaws.com/v1/control-channel/i-09d6333b4f1e77b79?role=subscribe&stream=input Dec 13 14:13:18.047124 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [MessageGatewayService] Successfully opened websocket connection to: wss://ssmmessages.us-west-2.amazonaws.com/v1/control-channel/i-09d6333b4f1e77b79?role=subscribe&stream=input Dec 13 14:13:18.147194 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [MessageGatewayService] Starting receiving message from control channel Dec 13 14:13:18.247330 amazon-ssm-agent[1754]: 2024-12-13 14:13:15 INFO [MessageGatewayService] [EngineProcessor] Initial processing Dec 13 14:13:18.546371 etcd-wrapper[2037]: d462aa345367: Verifying Checksum Dec 13 14:13:18.547061 etcd-wrapper[2037]: d462aa345367: Download complete Dec 13 14:13:18.592172 etcd-wrapper[2037]: d10f96ed9607: Verifying Checksum Dec 13 14:13:18.592513 etcd-wrapper[2037]: d10f96ed9607: Download complete Dec 13 14:13:18.694117 etcd-wrapper[2037]: 0f8b424aa0b9: Verifying Checksum Dec 13 14:13:18.694774 etcd-wrapper[2037]: 0f8b424aa0b9: Download complete Dec 13 14:13:18.736780 etcd-wrapper[2037]: d10f96ed9607: Pull complete Dec 13 14:13:18.818461 etcd-wrapper[2037]: d557676654e5: Verifying Checksum Dec 13 14:13:18.818461 etcd-wrapper[2037]: d557676654e5: Download complete Dec 13 14:13:18.869144 systemd[1]: var-lib-docker-overlay2-e153be6b8d0c5e10ea4c9b5afb42e00cc132b01efec497f2152400d23084a89c-merged.mount: Deactivated successfully. Dec 13 14:13:18.885368 etcd-wrapper[2037]: 2ae710cd8bfe: Pull complete Dec 13 14:13:19.056294 etcd-wrapper[2037]: d858cbc252ad: Verifying Checksum Dec 13 14:13:19.056294 etcd-wrapper[2037]: d858cbc252ad: Download complete Dec 13 14:13:19.058462 etcd-wrapper[2037]: c8022d07192e: Verifying Checksum Dec 13 14:13:19.058462 etcd-wrapper[2037]: c8022d07192e: Download complete Dec 13 14:13:19.110845 etcd-wrapper[2037]: 1069fc2daed1: Verifying Checksum Dec 13 14:13:19.110845 etcd-wrapper[2037]: 1069fc2daed1: Download complete Dec 13 14:13:19.218638 sshd_keygen[1732]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Dec 13 14:13:19.266513 systemd[1]: Finished sshd-keygen.service. Dec 13 14:13:19.271161 systemd[1]: Starting issuegen.service... Dec 13 14:13:19.283305 systemd[1]: issuegen.service: Deactivated successfully. Dec 13 14:13:19.283700 systemd[1]: Finished issuegen.service. Dec 13 14:13:19.288520 systemd[1]: Starting systemd-user-sessions.service... Dec 13 14:13:19.305645 systemd[1]: Finished systemd-user-sessions.service. Dec 13 14:13:19.310559 systemd[1]: Started getty@tty1.service. Dec 13 14:13:19.315072 systemd[1]: Started serial-getty@ttyS0.service. Dec 13 14:13:19.317221 systemd[1]: Reached target getty.target. Dec 13 14:13:19.383393 etcd-wrapper[2037]: 5318d93a3a65: Verifying Checksum Dec 13 14:13:19.383651 etcd-wrapper[2037]: 5318d93a3a65: Download complete Dec 13 14:13:19.521833 etcd-wrapper[2037]: 307c1adadb60: Verifying Checksum Dec 13 14:13:19.522232 etcd-wrapper[2037]: 307c1adadb60: Download complete Dec 13 14:13:19.646011 systemd[1]: var-lib-docker-overlay2-c483c3bde8aa02ea76df33536caa9e3790cfc5af3403e8473fce28381278a197-merged.mount: Deactivated successfully. Dec 13 14:13:19.706740 etcd-wrapper[2037]: d462aa345367: Pull complete Dec 13 14:13:19.787090 systemd[1]: var-lib-docker-overlay2-037bbc7bbe6e1a5fdd95856158ed618da959aa4f668b1ba8cfcc179f02aed645-merged.mount: Deactivated successfully. Dec 13 14:13:19.797214 etcd-wrapper[2037]: 0f8b424aa0b9: Pull complete Dec 13 14:13:19.884125 systemd[1]: var-lib-docker-overlay2-2b0f360ca18198ae9b9e3ee836a746358b478fb8127ab5c6997bdabbbb6711e4-merged.mount: Deactivated successfully. Dec 13 14:13:19.903538 etcd-wrapper[2037]: d557676654e5: Pull complete Dec 13 14:13:20.034743 etcd-wrapper[2037]: f9b3c65df792: Verifying Checksum Dec 13 14:13:20.034743 etcd-wrapper[2037]: f9b3c65df792: Download complete Dec 13 14:13:20.050146 systemd[1]: var-lib-docker-overlay2-922c13a4b1af79eb53a1339386bc9c51fd367718b4fc8bf60d6323529500577e-merged.mount: Deactivated successfully. Dec 13 14:13:20.086487 etcd-wrapper[2037]: c8022d07192e: Pull complete Dec 13 14:13:20.173231 etcd-wrapper[2037]: b40161cd83fc: Verifying Checksum Dec 13 14:13:20.173231 etcd-wrapper[2037]: b40161cd83fc: Download complete Dec 13 14:13:20.182946 etcd-wrapper[2037]: d858cbc252ad: Pull complete Dec 13 14:13:20.196010 etcd-wrapper[2037]: 5709b4a82c35: Verifying Checksum Dec 13 14:13:20.196436 etcd-wrapper[2037]: 5709b4a82c35: Download complete Dec 13 14:13:20.276168 etcd-wrapper[2037]: 1069fc2daed1: Pull complete Dec 13 14:13:20.369827 etcd-wrapper[2037]: b40161cd83fc: Pull complete Dec 13 14:13:20.470670 etcd-wrapper[2037]: 5318d93a3a65: Pull complete Dec 13 14:13:20.575495 etcd-wrapper[2037]: 307c1adadb60: Pull complete Dec 13 14:13:20.854750 etcd-wrapper[2037]: 9c3b5253dddc: Verifying Checksum Dec 13 14:13:20.855482 etcd-wrapper[2037]: 9c3b5253dddc: Download complete Dec 13 14:13:21.083913 systemd[1]: var-lib-docker-overlay2-cdba30a96bcf5b76bd75d25917ae12950168c033b2c3390dbf31b8b37ec52947-merged.mount: Deactivated successfully. Dec 13 14:13:21.137907 etcd-wrapper[2037]: f9b3c65df792: Pull complete Dec 13 14:13:21.139337 etcd-wrapper[2037]: 7a57323f7551: Verifying Checksum Dec 13 14:13:21.139608 etcd-wrapper[2037]: 7a57323f7551: Download complete Dec 13 14:13:21.436715 etcd-wrapper[2037]: d5372e382b07: Verifying Checksum Dec 13 14:13:21.436715 etcd-wrapper[2037]: d5372e382b07: Download complete Dec 13 14:13:21.533740 systemd[1]: var-lib-docker-overlay2-33f18b39100cdb77ae99abce1c893a2bc945ecf49fa09b5e53a2ba05b40ed71c-merged.mount: Deactivated successfully. Dec 13 14:13:21.583186 etcd-wrapper[2037]: 5709b4a82c35: Pull complete Dec 13 14:13:21.950581 systemd[1]: var-lib-docker-overlay2-b88e02dd03e2b53c27fc0e8ea64866a6be686c828b4919968de4ce0cb452bf59-merged.mount: Deactivated successfully. Dec 13 14:13:21.987903 etcd-wrapper[2037]: d5372e382b07: Pull complete Dec 13 14:13:22.070378 systemd[1]: var-lib-docker-overlay2-97e4e9cdd9e89fe99f8ca16fbbb63310c9e834b5ac21916751463ad66bad8dec-merged.mount: Deactivated successfully. Dec 13 14:13:22.083830 etcd-wrapper[2037]: 9c3b5253dddc: Pull complete Dec 13 14:13:22.162493 systemd[1]: var-lib-docker-overlay2-43f304d6a45feb11637a38cbe3099cf100e521d5a5a03879f696d26a03cf5626-merged.mount: Deactivated successfully. Dec 13 14:13:22.177515 etcd-wrapper[2037]: 7a57323f7551: Pull complete Dec 13 14:13:22.190641 etcd-wrapper[2037]: Digest: sha256:d967d98a12dc220a1a290794711dba7eba04b8ce465e12b02383d1bfbb33e159 Dec 13 14:13:22.194701 etcd-wrapper[2037]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.16 Dec 13 14:13:22.365300 env[1726]: time="2024-12-13T14:13:22.364801687Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Dec 13 14:13:22.365300 env[1726]: time="2024-12-13T14:13:22.364921423Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Dec 13 14:13:22.365300 env[1726]: time="2024-12-13T14:13:22.364949827Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Dec 13 14:13:22.366292 env[1726]: time="2024-12-13T14:13:22.366167659Z" level=info msg="starting signal loop" namespace=moby path=/run/containerd/io.containerd.runtime.v2.task/moby/2af58da8cb86bebbeb743dafc99a082765d06db627d9fa4e5e921d1a04c69b93 pid=2175 runtime=io.containerd.runc.v2 Dec 13 14:13:22.388943 systemd[1]: Started docker-2af58da8cb86bebbeb743dafc99a082765d06db627d9fa4e5e921d1a04c69b93.scope. Dec 13 14:13:22.484758 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.482010Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} Dec 13 14:13:22.484758 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.482242Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"ec27bc6a5f32b41c3b9acc277702825f"} Dec 13 14:13:22.484758 etcd-wrapper[2037]: {"level":"warn","ts":"2024-12-13T14:13:22.482294Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} Dec 13 14:13:22.484758 etcd-wrapper[2037]: {"level":"warn","ts":"2024-12-13T14:13:22.482312Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.16"} Dec 13 14:13:22.484758 etcd-wrapper[2037]: {"level":"warn","ts":"2024-12-13T14:13:22.482325Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} Dec 13 14:13:22.484758 etcd-wrapper[2037]: {"level":"warn","ts":"2024-12-13T14:13:22.482337Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} Dec 13 14:13:22.484758 etcd-wrapper[2037]: {"level":"warn","ts":"2024-12-13T14:13:22.482384Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Dec 13 14:13:22.484758 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.482412Z","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.23.153:2379"]} Dec 13 14:13:22.484758 etcd-wrapper[2037]: {"level":"warn","ts":"2024-12-13T14:13:22.482520Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Dec 13 14:13:22.484758 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.482541Z","caller":"embed/etcd.go:128","msg":"configuring peer listeners","listen-peer-urls":["http://localhost:2380"]} Dec 13 14:13:22.484758 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.483292Z","caller":"embed/etcd.go:136","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} Dec 13 14:13:22.485498 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.483501Z","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":"ec27bc6a5f32b41c3b9acc277702825f","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.23.153:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"ec27bc6a5f32b41c3b9acc277702825f=http://localhost:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":false,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} Dec 13 14:13:22.486161 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.485850Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"1.8387ms"} Dec 13 14:13:22.490918 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.490559Z","caller":"etcdserver/raft.go:505","msg":"starting local member","local-member-id":"8e9e05c52164694d","cluster-id":"cdf818194e3a8c32"} Dec 13 14:13:22.491338 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.490984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=()"} Dec 13 14:13:22.491338 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.491095Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 0"} Dec 13 14:13:22.491338 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.491186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8e9e05c52164694d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} Dec 13 14:13:22.491740 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.491381Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 1"} Dec 13 14:13:22.491740 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.491485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Dec 13 14:13:22.495914 etcd-wrapper[2037]: {"level":"warn","ts":"2024-12-13T14:13:22.495585Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} Dec 13 14:13:22.497119 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.496825Z","caller":"mvcc/kvstore.go:423","msg":"kvstore restored","current-rev":1} Dec 13 14:13:22.498070 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.497799Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} Dec 13 14:13:22.500199 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.499914Z","caller":"etcdserver/server.go:873","msg":"starting etcd server","local-member-id":"8e9e05c52164694d","local-server-version":"3.5.16","cluster-version":"to_be_decided"} Dec 13 14:13:22.500755 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.500457Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Dec 13 14:13:22.501605 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.501297Z","caller":"etcdserver/server.go:757","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8e9e05c52164694d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} Dec 13 14:13:22.502180 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.501850Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} Dec 13 14:13:22.502180 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.501942Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} Dec 13 14:13:22.502526 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.501965Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} Dec 13 14:13:22.503238 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.502955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Dec 13 14:13:22.503677 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.503431Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","added-peer-id":"8e9e05c52164694d","added-peer-peer-urls":["http://localhost:2380"]} Dec 13 14:13:22.507372 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.507008Z","caller":"embed/etcd.go:600","msg":"serving peer traffic","address":"127.0.0.1:2380"} Dec 13 14:13:22.507372 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.507059Z","caller":"embed/etcd.go:572","msg":"cmux::serve","address":"127.0.0.1:2380"} Dec 13 14:13:22.507372 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.507048Z","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.23.153:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} Dec 13 14:13:22.692449 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.692006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d is starting a new election at term 1"} Dec 13 14:13:22.692449 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.692146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became pre-candidate at term 1"} Dec 13 14:13:22.692917 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.692413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgPreVoteResp from 8e9e05c52164694d at term 1"} Dec 13 14:13:22.692917 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.692496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became candidate at term 2"} Dec 13 14:13:22.692917 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.692548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 2"} Dec 13 14:13:22.692917 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.692637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became leader at term 2"} Dec 13 14:13:22.693219 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.692688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 2"} Dec 13 14:13:22.694127 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.693827Z","caller":"etcdserver/server.go:2651","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} Dec 13 14:13:22.695000 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.694698Z","caller":"etcdserver/server.go:2140","msg":"published local member to cluster through raft","local-member-id":"8e9e05c52164694d","local-member-attributes":"{Name:ec27bc6a5f32b41c3b9acc277702825f ClientURLs:[http://172.31.23.153:2379]}","request-path":"/0/members/8e9e05c52164694d/attributes","cluster-id":"cdf818194e3a8c32","publish-timeout":"7s"} Dec 13 14:13:22.695331 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.695091Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} Dec 13 14:13:22.696101 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.695783Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} Dec 13 14:13:22.696457 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.696173Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","cluster-version":"3.5"} Dec 13 14:13:22.696743 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.696438Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} Dec 13 14:13:22.696743 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.696543Z","caller":"etcdserver/server.go:2675","msg":"cluster version is updated","cluster-version":"3.5"} Dec 13 14:13:22.697268 systemd[1]: Started etcd-member.service. Dec 13 14:13:22.703040 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.696975Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Dec 13 14:13:22.703040 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.699363Z","caller":"embed/serve.go:187","msg":"serving client traffic insecurely; this is strongly discouraged!","traffic":"grpc+http","address":"[::]:2379"} Dec 13 14:13:22.699315 systemd[1]: Reached target multi-user.target. Dec 13 14:13:22.703659 systemd[1]: Starting systemd-update-utmp-runlevel.service... Dec 13 14:13:22.707427 etcd-wrapper[2037]: {"level":"info","ts":"2024-12-13T14:13:22.707027Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} Dec 13 14:13:22.720364 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Dec 13 14:13:22.720774 systemd[1]: Finished systemd-update-utmp-runlevel.service. Dec 13 14:13:22.722960 systemd[1]: Startup finished in 1.124s (kernel) + 7.653s (initrd) + 17.479s (userspace) = 26.257s. Dec 13 14:13:22.975772 systemd[1]: Created slice system-sshd.slice. Dec 13 14:13:22.978920 systemd[1]: Started sshd@0-172.31.23.153:22-139.178.89.65:39574.service. Dec 13 14:13:23.176250 sshd[2218]: Accepted publickey for core from 139.178.89.65 port 39574 ssh2: RSA SHA256:07jB2DPJgjjhgg3L8Uh349EZ0zHZFrUiRWNbK+Fdo0Q Dec 13 14:13:23.184128 sshd[2218]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Dec 13 14:13:23.208528 systemd[1]: Created slice user-500.slice. Dec 13 14:13:23.210948 systemd[1]: Starting user-runtime-dir@500.service... Dec 13 14:13:23.218751 systemd-logind[1715]: New session 1 of user core. Dec 13 14:13:23.229718 systemd[1]: Finished user-runtime-dir@500.service. Dec 13 14:13:23.233048 systemd[1]: Starting user@500.service... Dec 13 14:13:23.241345 (systemd)[2221]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Dec 13 14:13:23.426354 systemd[2221]: Queued start job for default target default.target. Dec 13 14:13:23.428530 systemd[2221]: Reached target paths.target. Dec 13 14:13:23.428774 systemd[2221]: Reached target sockets.target. Dec 13 14:13:23.429049 systemd[2221]: Reached target timers.target. Dec 13 14:13:23.429212 systemd[2221]: Reached target basic.target. Dec 13 14:13:23.429432 systemd[2221]: Reached target default.target. Dec 13 14:13:23.429524 systemd[1]: Started user@500.service. Dec 13 14:13:23.430234 systemd[2221]: Startup finished in 177ms. Dec 13 14:13:23.432609 systemd[1]: Started session-1.scope. Dec 13 14:13:23.578781 systemd[1]: Started sshd@1-172.31.23.153:22-139.178.89.65:39578.service. Dec 13 14:13:23.747701 sshd[2230]: Accepted publickey for core from 139.178.89.65 port 39578 ssh2: RSA SHA256:07jB2DPJgjjhgg3L8Uh349EZ0zHZFrUiRWNbK+Fdo0Q Dec 13 14:13:23.750120 sshd[2230]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Dec 13 14:13:23.758334 systemd-logind[1715]: New session 2 of user core. Dec 13 14:13:23.759210 systemd[1]: Started session-2.scope. Dec 13 14:13:23.892474 sshd[2230]: pam_unix(sshd:session): session closed for user core Dec 13 14:13:23.897488 systemd[1]: sshd@1-172.31.23.153:22-139.178.89.65:39578.service: Deactivated successfully. Dec 13 14:13:23.898828 systemd[1]: session-2.scope: Deactivated successfully. Dec 13 14:13:23.900036 systemd-logind[1715]: Session 2 logged out. Waiting for processes to exit. Dec 13 14:13:23.902031 systemd-logind[1715]: Removed session 2. Dec 13 14:13:23.920075 systemd[1]: Started sshd@2-172.31.23.153:22-139.178.89.65:39586.service. Dec 13 14:13:24.084633 sshd[2236]: Accepted publickey for core from 139.178.89.65 port 39586 ssh2: RSA SHA256:07jB2DPJgjjhgg3L8Uh349EZ0zHZFrUiRWNbK+Fdo0Q Dec 13 14:13:24.087575 sshd[2236]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Dec 13 14:13:24.095779 systemd[1]: Started session-3.scope. Dec 13 14:13:24.096993 systemd-logind[1715]: New session 3 of user core. Dec 13 14:13:24.217107 sshd[2236]: pam_unix(sshd:session): session closed for user core Dec 13 14:13:24.222836 systemd[1]: sshd@2-172.31.23.153:22-139.178.89.65:39586.service: Deactivated successfully. Dec 13 14:13:24.224090 systemd[1]: session-3.scope: Deactivated successfully. Dec 13 14:13:24.225321 systemd-logind[1715]: Session 3 logged out. Waiting for processes to exit. Dec 13 14:13:24.227046 systemd-logind[1715]: Removed session 3. Dec 13 14:13:24.245042 systemd[1]: Started sshd@3-172.31.23.153:22-139.178.89.65:39600.service. Dec 13 14:13:24.413950 sshd[2242]: Accepted publickey for core from 139.178.89.65 port 39600 ssh2: RSA SHA256:07jB2DPJgjjhgg3L8Uh349EZ0zHZFrUiRWNbK+Fdo0Q Dec 13 14:13:24.416921 sshd[2242]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Dec 13 14:13:24.425017 systemd[1]: Started session-4.scope. Dec 13 14:13:24.425950 systemd-logind[1715]: New session 4 of user core. Dec 13 14:13:24.555931 sshd[2242]: pam_unix(sshd:session): session closed for user core Dec 13 14:13:24.560842 systemd[1]: session-4.scope: Deactivated successfully. Dec 13 14:13:24.561999 systemd[1]: sshd@3-172.31.23.153:22-139.178.89.65:39600.service: Deactivated successfully. Dec 13 14:13:24.563550 systemd-logind[1715]: Session 4 logged out. Waiting for processes to exit. Dec 13 14:13:24.565247 systemd-logind[1715]: Removed session 4. Dec 13 14:13:24.584809 systemd[1]: Started sshd@4-172.31.23.153:22-139.178.89.65:39602.service. Dec 13 14:13:24.756291 sshd[2248]: Accepted publickey for core from 139.178.89.65 port 39602 ssh2: RSA SHA256:07jB2DPJgjjhgg3L8Uh349EZ0zHZFrUiRWNbK+Fdo0Q Dec 13 14:13:24.759242 sshd[2248]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Dec 13 14:13:24.767523 systemd[1]: Started session-5.scope. Dec 13 14:13:24.769818 systemd-logind[1715]: New session 5 of user core. Dec 13 14:13:24.892218 sudo[2251]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Dec 13 14:13:24.893383 sudo[2251]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Dec 13 14:13:24.907141 dbus-daemon[1708]: avc: received setenforce notice (enforcing=1) Dec 13 14:13:24.909778 sudo[2251]: pam_unix(sudo:session): session closed for user root Dec 13 14:13:24.936932 sshd[2248]: pam_unix(sshd:session): session closed for user core Dec 13 14:13:24.941978 systemd-logind[1715]: Session 5 logged out. Waiting for processes to exit. Dec 13 14:13:24.942664 systemd[1]: sshd@4-172.31.23.153:22-139.178.89.65:39602.service: Deactivated successfully. Dec 13 14:13:24.943951 systemd[1]: session-5.scope: Deactivated successfully. Dec 13 14:13:24.945508 systemd-logind[1715]: Removed session 5. Dec 13 14:13:24.962386 systemd[1]: Started sshd@5-172.31.23.153:22-139.178.89.65:39618.service. Dec 13 14:13:25.133003 sshd[2255]: Accepted publickey for core from 139.178.89.65 port 39618 ssh2: RSA SHA256:07jB2DPJgjjhgg3L8Uh349EZ0zHZFrUiRWNbK+Fdo0Q Dec 13 14:13:25.134683 sshd[2255]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Dec 13 14:13:25.142660 systemd-logind[1715]: New session 6 of user core. Dec 13 14:13:25.143544 systemd[1]: Started session-6.scope. Dec 13 14:13:25.249613 sudo[2259]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Dec 13 14:13:25.250143 sudo[2259]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Dec 13 14:13:25.255239 sudo[2259]: pam_unix(sudo:session): session closed for user root Dec 13 14:13:25.264257 sudo[2258]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/systemctl restart audit-rules Dec 13 14:13:25.265204 sudo[2258]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Dec 13 14:13:25.282298 systemd[1]: Stopping audit-rules.service... Dec 13 14:13:25.282000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Dec 13 14:13:25.286141 kernel: kauditd_printk_skb: 72 callbacks suppressed Dec 13 14:13:25.286223 kernel: audit: type=1305 audit(1734099205.282:165): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Dec 13 14:13:25.282000 audit[2262]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffd6875230 a2=420 a3=0 items=0 ppid=1 pid=2262 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditctl" exe="/usr/sbin/auditctl" subj=system_u:system_r:kernel_t:s0 key=(null) Dec 13 14:13:25.291455 auditctl[2262]: No rules Dec 13 14:13:25.292379 systemd[1]: audit-rules.service: Deactivated successfully. Dec 13 14:13:25.292774 systemd[1]: Stopped audit-rules.service. Dec 13 14:13:25.300915 kernel: audit: type=1300 audit(1734099205.282:165): arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffd6875230 a2=420 a3=0 items=0 ppid=1 pid=2262 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditctl" exe="/usr/sbin/auditctl" subj=system_u:system_r:kernel_t:s0 key=(null) Dec 13 14:13:25.302378 systemd[1]: Starting audit-rules.service... Dec 13 14:13:25.282000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D44 Dec 13 14:13:25.290000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=audit-rules comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:25.315784 kernel: audit: type=1327 audit(1734099205.282:165): proctitle=2F7362696E2F617564697463746C002D44 Dec 13 14:13:25.315921 kernel: audit: type=1131 audit(1734099205.290:166): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=audit-rules comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:25.335151 augenrules[2279]: No rules Dec 13 14:13:25.336783 systemd[1]: Finished audit-rules.service. Dec 13 14:13:25.335000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=audit-rules comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:25.339850 sudo[2258]: pam_unix(sudo:session): session closed for user root Dec 13 14:13:25.336000 audit[2258]: USER_END pid=2258 uid=500 auid=500 ses=6 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Dec 13 14:13:25.354238 kernel: audit: type=1130 audit(1734099205.335:167): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=audit-rules comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:25.354311 kernel: audit: type=1106 audit(1734099205.336:168): pid=2258 uid=500 auid=500 ses=6 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Dec 13 14:13:25.336000 audit[2258]: CRED_DISP pid=2258 uid=500 auid=500 ses=6 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Dec 13 14:13:25.361634 kernel: audit: type=1104 audit(1734099205.336:169): pid=2258 uid=500 auid=500 ses=6 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Dec 13 14:13:25.369229 sshd[2255]: pam_unix(sshd:session): session closed for user core Dec 13 14:13:25.368000 audit[2255]: USER_END pid=2255 uid=0 auid=500 ses=6 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=139.178.89.65 addr=139.178.89.65 terminal=ssh res=success' Dec 13 14:13:25.381663 systemd[1]: sshd@5-172.31.23.153:22-139.178.89.65:39618.service: Deactivated successfully. Dec 13 14:13:25.383628 kernel: audit: type=1106 audit(1734099205.368:170): pid=2255 uid=0 auid=500 ses=6 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=139.178.89.65 addr=139.178.89.65 terminal=ssh res=success' Dec 13 14:13:25.383749 kernel: audit: type=1104 audit(1734099205.369:171): pid=2255 uid=0 auid=500 ses=6 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=139.178.89.65 addr=139.178.89.65 terminal=ssh res=success' Dec 13 14:13:25.369000 audit[2255]: CRED_DISP pid=2255 uid=0 auid=500 ses=6 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=139.178.89.65 addr=139.178.89.65 terminal=ssh res=success' Dec 13 14:13:25.382908 systemd[1]: session-6.scope: Deactivated successfully. Dec 13 14:13:25.380000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@5-172.31.23.153:22-139.178.89.65:39618 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:25.392632 kernel: audit: type=1131 audit(1734099205.380:172): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@5-172.31.23.153:22-139.178.89.65:39618 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:25.391969 systemd-logind[1715]: Session 6 logged out. Waiting for processes to exit. Dec 13 14:13:25.399000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@6-172.31.23.153:22-139.178.89.65:39626 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:25.399501 systemd[1]: Started sshd@6-172.31.23.153:22-139.178.89.65:39626.service. Dec 13 14:13:25.404561 systemd-logind[1715]: Removed session 6. Dec 13 14:13:25.565000 audit[2285]: USER_ACCT pid=2285 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:accounting grantors=pam_access,pam_unix,pam_faillock,pam_permit acct="core" exe="/usr/sbin/sshd" hostname=139.178.89.65 addr=139.178.89.65 terminal=ssh res=success' Dec 13 14:13:25.567214 sshd[2285]: Accepted publickey for core from 139.178.89.65 port 39626 ssh2: RSA SHA256:07jB2DPJgjjhgg3L8Uh349EZ0zHZFrUiRWNbK+Fdo0Q Dec 13 14:13:25.566000 audit[2285]: CRED_ACQ pid=2285 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=139.178.89.65 addr=139.178.89.65 terminal=ssh res=success' Dec 13 14:13:25.567000 audit[2285]: SYSCALL arch=c00000b7 syscall=64 success=yes exit=3 a0=5 a1=ffffc062a490 a2=3 a3=1 items=0 ppid=1 pid=2285 auid=500 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=7 comm="sshd" exe="/usr/sbin/sshd" subj=system_u:system_r:kernel_t:s0 key=(null) Dec 13 14:13:25.567000 audit: PROCTITLE proctitle=737368643A20636F7265205B707269765D Dec 13 14:13:25.570149 sshd[2285]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Dec 13 14:13:25.578720 systemd[1]: Started session-7.scope. Dec 13 14:13:25.579770 systemd-logind[1715]: New session 7 of user core. Dec 13 14:13:25.586000 audit[2285]: USER_START pid=2285 uid=0 auid=500 ses=7 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_open grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=139.178.89.65 addr=139.178.89.65 terminal=ssh res=success' Dec 13 14:13:25.589000 audit[2287]: CRED_ACQ pid=2287 uid=0 auid=500 ses=7 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=139.178.89.65 addr=139.178.89.65 terminal=ssh res=success' Dec 13 14:13:25.702416 sshd[2285]: pam_unix(sshd:session): session closed for user core Dec 13 14:13:25.702000 audit[2285]: USER_END pid=2285 uid=0 auid=500 ses=7 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=139.178.89.65 addr=139.178.89.65 terminal=ssh res=success' Dec 13 14:13:25.702000 audit[2285]: CRED_DISP pid=2285 uid=0 auid=500 ses=7 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=139.178.89.65 addr=139.178.89.65 terminal=ssh res=success' Dec 13 14:13:25.706817 systemd[1]: session-7.scope: Deactivated successfully. Dec 13 14:13:25.707965 systemd[1]: sshd@6-172.31.23.153:22-139.178.89.65:39626.service: Deactivated successfully. Dec 13 14:13:25.706000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@6-172.31.23.153:22-139.178.89.65:39626 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:25.709664 systemd-logind[1715]: Session 7 logged out. Waiting for processes to exit. Dec 13 14:13:25.711393 systemd-logind[1715]: Removed session 7. Dec 13 14:13:25.726000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@7-172.31.23.153:22-139.178.89.65:39628 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:25.728465 systemd[1]: Started sshd@7-172.31.23.153:22-139.178.89.65:39628.service. Dec 13 14:13:25.893000 audit[2293]: USER_ACCT pid=2293 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:accounting grantors=pam_access,pam_unix,pam_faillock,pam_permit acct="core" exe="/usr/sbin/sshd" hostname=139.178.89.65 addr=139.178.89.65 terminal=ssh res=success' Dec 13 14:13:25.895282 sshd[2293]: Accepted publickey for core from 139.178.89.65 port 39628 ssh2: RSA SHA256:07jB2DPJgjjhgg3L8Uh349EZ0zHZFrUiRWNbK+Fdo0Q Dec 13 14:13:25.895000 audit[2293]: CRED_ACQ pid=2293 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=139.178.89.65 addr=139.178.89.65 terminal=ssh res=success' Dec 13 14:13:25.895000 audit[2293]: SYSCALL arch=c00000b7 syscall=64 success=yes exit=3 a0=5 a1=ffffc20e3d90 a2=3 a3=1 items=0 ppid=1 pid=2293 auid=500 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=8 comm="sshd" exe="/usr/sbin/sshd" subj=system_u:system_r:kernel_t:s0 key=(null) Dec 13 14:13:25.895000 audit: PROCTITLE proctitle=737368643A20636F7265205B707269765D Dec 13 14:13:25.898147 sshd[2293]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Dec 13 14:13:25.906945 systemd[1]: Started session-8.scope. Dec 13 14:13:25.906965 systemd-logind[1715]: New session 8 of user core. Dec 13 14:13:25.914000 audit[2293]: USER_START pid=2293 uid=0 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_open grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=139.178.89.65 addr=139.178.89.65 terminal=ssh res=success' Dec 13 14:13:25.917000 audit[2295]: CRED_ACQ pid=2295 uid=0 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=139.178.89.65 addr=139.178.89.65 terminal=ssh res=success' Dec 13 14:13:26.200115 sshd[2293]: pam_unix(sshd:session): session closed for user core Dec 13 14:13:26.200000 audit[2293]: USER_END pid=2293 uid=0 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=139.178.89.65 addr=139.178.89.65 terminal=ssh res=success' Dec 13 14:13:26.201000 audit[2293]: CRED_DISP pid=2293 uid=0 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=139.178.89.65 addr=139.178.89.65 terminal=ssh res=success' Dec 13 14:13:26.205628 systemd-logind[1715]: Session 8 logged out. Waiting for processes to exit. Dec 13 14:13:26.206390 systemd[1]: session-8.scope: Deactivated successfully. Dec 13 14:13:26.207468 systemd[1]: sshd@7-172.31.23.153:22-139.178.89.65:39628.service: Deactivated successfully. Dec 13 14:13:26.206000 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.23.153:22-139.178.89.65:39628 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:26.209545 systemd-logind[1715]: Removed session 8. Dec 13 14:13:35.710232 amazon-ssm-agent[1754]: 2024-12-13 14:13:35 INFO [MessagingDeliveryService] [Association] No associations on boot. Requerying for associations after 30 seconds. Dec 13 14:13:44.494692 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Dec 13 14:13:44.494000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-hostnamed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:44.497148 kernel: kauditd_printk_skb: 22 callbacks suppressed Dec 13 14:13:44.497232 kernel: audit: type=1131 audit(1734099224.494:191): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-hostnamed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:13:44.531000 audit: BPF prog-id=34 op=UNLOAD Dec 13 14:13:44.531000 audit: BPF prog-id=33 op=UNLOAD Dec 13 14:13:44.536821 kernel: audit: type=1334 audit(1734099224.531:192): prog-id=34 op=UNLOAD Dec 13 14:13:44.536899 kernel: audit: type=1334 audit(1734099224.531:193): prog-id=33 op=UNLOAD Dec 13 14:13:44.536946 kernel: audit: type=1334 audit(1734099224.531:194): prog-id=32 op=UNLOAD Dec 13 14:13:44.531000 audit: BPF prog-id=32 op=UNLOAD Dec 13 14:13:45.852660 systemd-timesyncd[1680]: Contacted time server 209.51.161.238:123 (2.flatcar.pool.ntp.org). Dec 13 14:13:45.852775 systemd-timesyncd[1680]: Initial clock synchronization to Fri 2024-12-13 14:13:45.659877 UTC. Dec 13 14:13:59.481551 update_engine[1716]: I1213 14:13:59.481034 1716 update_attempter.cc:509] Updating boot flags... Dec 13 14:14:05.736541 amazon-ssm-agent[1754]: 2024-12-13 14:14:05 INFO [MessagingDeliveryService] [Association] Schedule manager refreshed with 0 associations, 0 new associations associated Dec 13 14:15:22.035591 amazon-ssm-agent[1754]: 2024-12-13 14:15:22 INFO [HealthCheck] HealthCheck reporting agent health.