Oct 2 19:36:18.155125 kernel: Booting Linux on physical CPU 0x0000000000 [0x410fd083] Oct 2 19:36:18.155164 kernel: Linux version 5.15.132-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 Mon Oct 2 17:55:37 -00 2023 Oct 2 19:36:18.155186 kernel: efi: EFI v2.70 by EDK II Oct 2 19:36:18.155201 kernel: efi: SMBIOS=0x7bed0000 SMBIOS 3.0=0x7beb0000 ACPI=0x786e0000 ACPI 2.0=0x786e0014 MEMATTR=0x7ac1aa98 MEMRESERVE=0x71accf98 Oct 2 19:36:18.155214 kernel: ACPI: Early table checksum verification disabled Oct 2 19:36:18.155228 kernel: ACPI: RSDP 0x00000000786E0014 000024 (v02 AMAZON) Oct 2 19:36:18.155244 kernel: ACPI: XSDT 0x00000000786D00E8 000064 (v01 AMAZON AMZNFACP 00000001 01000013) Oct 2 19:36:18.155258 kernel: ACPI: FACP 0x00000000786B0000 000114 (v06 AMAZON AMZNFACP 00000001 AMZN 00000001) Oct 2 19:36:18.155272 kernel: ACPI: DSDT 0x0000000078640000 00154F (v02 AMAZON AMZNDSDT 00000001 INTL 20160527) Oct 2 19:36:18.155285 kernel: ACPI: APIC 0x00000000786C0000 000108 (v04 AMAZON AMZNAPIC 00000001 AMZN 00000001) Oct 2 19:36:18.155303 kernel: ACPI: SPCR 0x00000000786A0000 000050 (v02 AMAZON AMZNSPCR 00000001 AMZN 00000001) Oct 2 19:36:18.155317 kernel: ACPI: GTDT 0x0000000078690000 000060 (v02 AMAZON AMZNGTDT 00000001 AMZN 00000001) Oct 2 19:36:18.155331 kernel: ACPI: MCFG 0x0000000078680000 00003C (v02 AMAZON AMZNMCFG 00000001 AMZN 00000001) Oct 2 19:36:18.155345 kernel: ACPI: SLIT 0x0000000078670000 00002D (v01 AMAZON AMZNSLIT 00000001 AMZN 00000001) Oct 2 19:36:18.155361 kernel: ACPI: IORT 0x0000000078660000 000078 (v01 AMAZON AMZNIORT 00000001 AMZN 00000001) Oct 2 19:36:18.155380 kernel: ACPI: PPTT 0x0000000078650000 0000EC (v01 AMAZON AMZNPPTT 00000001 AMZN 00000001) Oct 2 19:36:18.155394 kernel: ACPI: SPCR: console: uart,mmio,0x90a0000,115200 Oct 2 19:36:18.155409 kernel: earlycon: uart0 at MMIO 0x00000000090a0000 (options '115200') Oct 2 19:36:18.155423 kernel: printk: bootconsole [uart0] enabled Oct 2 19:36:18.155437 kernel: NUMA: Failed to initialise from firmware Oct 2 19:36:18.155452 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000004b5ffffff] Oct 2 19:36:18.155466 kernel: NUMA: NODE_DATA [mem 0x4b5841900-0x4b5846fff] Oct 2 19:36:18.155481 kernel: Zone ranges: Oct 2 19:36:18.155495 kernel: DMA [mem 0x0000000040000000-0x00000000ffffffff] Oct 2 19:36:18.155509 kernel: DMA32 empty Oct 2 19:36:18.155524 kernel: Normal [mem 0x0000000100000000-0x00000004b5ffffff] Oct 2 19:36:18.155542 kernel: Movable zone start for each node Oct 2 19:36:18.155556 kernel: Early memory node ranges Oct 2 19:36:18.155571 kernel: node 0: [mem 0x0000000040000000-0x00000000786effff] Oct 2 19:36:18.155585 kernel: node 0: [mem 0x00000000786f0000-0x000000007872ffff] Oct 2 19:36:18.155599 kernel: node 0: [mem 0x0000000078730000-0x000000007bbfffff] Oct 2 19:36:18.155614 kernel: node 0: [mem 0x000000007bc00000-0x000000007bfdffff] Oct 2 19:36:18.155628 kernel: node 0: [mem 0x000000007bfe0000-0x000000007fffffff] Oct 2 19:36:18.155642 kernel: node 0: [mem 0x0000000400000000-0x00000004b5ffffff] Oct 2 19:36:18.155656 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000004b5ffffff] Oct 2 19:36:18.155671 kernel: On node 0, zone Normal: 8192 pages in unavailable ranges Oct 2 19:36:18.155702 kernel: psci: probing for conduit method from ACPI. Oct 2 19:36:18.155720 kernel: psci: PSCIv1.0 detected in firmware. Oct 2 19:36:18.155740 kernel: psci: Using standard PSCI v0.2 function IDs Oct 2 19:36:18.155756 kernel: psci: Trusted OS migration not required Oct 2 19:36:18.155777 kernel: psci: SMC Calling Convention v1.1 Oct 2 19:36:18.155793 kernel: ACPI: SRAT not present Oct 2 19:36:18.155809 kernel: percpu: Embedded 29 pages/cpu s79960 r8192 d30632 u118784 Oct 2 19:36:18.155828 kernel: pcpu-alloc: s79960 r8192 d30632 u118784 alloc=29*4096 Oct 2 19:36:18.155843 kernel: pcpu-alloc: [0] 0 [0] 1 Oct 2 19:36:18.155858 kernel: Detected PIPT I-cache on CPU0 Oct 2 19:36:18.155874 kernel: CPU features: detected: GIC system register CPU interface Oct 2 19:36:18.155889 kernel: CPU features: detected: Spectre-v2 Oct 2 19:36:18.155904 kernel: CPU features: detected: Spectre-v3a Oct 2 19:36:18.155919 kernel: CPU features: detected: Spectre-BHB Oct 2 19:36:18.155934 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 2 19:36:18.155949 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 2 19:36:18.155964 kernel: CPU features: detected: ARM erratum 1742098 Oct 2 19:36:18.155979 kernel: CPU features: detected: ARM errata 1165522, 1319367, or 1530923 Oct 2 19:36:18.155998 kernel: Built 1 zonelists, mobility grouping on. Total pages: 991872 Oct 2 19:36:18.156013 kernel: Policy zone: Normal Oct 2 19:36:18.156031 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=684fe6a2259d7fb96810743ab87aaaa03d9f185b113bd6990a64d1079e5672ca Oct 2 19:36:18.156067 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Oct 2 19:36:18.156084 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 2 19:36:18.156099 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 2 19:36:18.156115 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 2 19:36:18.156131 kernel: software IO TLB: mapped [mem 0x000000007c000000-0x0000000080000000] (64MB) Oct 2 19:36:18.156147 kernel: Memory: 3826444K/4030464K available (9792K kernel code, 2092K rwdata, 7548K rodata, 34560K init, 779K bss, 204020K reserved, 0K cma-reserved) Oct 2 19:36:18.156163 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Oct 2 19:36:18.156183 kernel: trace event string verifier disabled Oct 2 19:36:18.156198 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 2 19:36:18.156214 kernel: rcu: RCU event tracing is enabled. Oct 2 19:36:18.156229 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Oct 2 19:36:18.156245 kernel: Trampoline variant of Tasks RCU enabled. Oct 2 19:36:18.156260 kernel: Tracing variant of Tasks RCU enabled. Oct 2 19:36:18.156276 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 2 19:36:18.156291 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Oct 2 19:36:18.156306 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 2 19:36:18.156321 kernel: GICv3: 96 SPIs implemented Oct 2 19:36:18.156336 kernel: GICv3: 0 Extended SPIs implemented Oct 2 19:36:18.156350 kernel: GICv3: Distributor has no Range Selector support Oct 2 19:36:18.156369 kernel: Root IRQ handler: gic_handle_irq Oct 2 19:36:18.156384 kernel: GICv3: 16 PPIs implemented Oct 2 19:36:18.156399 kernel: GICv3: CPU0: found redistributor 0 region 0:0x0000000010200000 Oct 2 19:36:18.156414 kernel: ACPI: SRAT not present Oct 2 19:36:18.156428 kernel: ITS [mem 0x10080000-0x1009ffff] Oct 2 19:36:18.156444 kernel: ITS@0x0000000010080000: allocated 8192 Devices @4000a0000 (indirect, esz 8, psz 64K, shr 1) Oct 2 19:36:18.156459 kernel: ITS@0x0000000010080000: allocated 8192 Interrupt Collections @4000b0000 (flat, esz 8, psz 64K, shr 1) Oct 2 19:36:18.156474 kernel: GICv3: using LPI property table @0x00000004000c0000 Oct 2 19:36:18.156489 kernel: ITS: Using hypervisor restricted LPI range [128] Oct 2 19:36:18.156505 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000004000d0000 Oct 2 19:36:18.156519 kernel: arch_timer: cp15 timer(s) running at 83.33MHz (virt). Oct 2 19:36:18.156538 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x13381ebeec, max_idle_ns: 440795203145 ns Oct 2 19:36:18.156554 kernel: sched_clock: 56 bits at 83MHz, resolution 12ns, wraps every 4398046511100ns Oct 2 19:36:18.156569 kernel: Console: colour dummy device 80x25 Oct 2 19:36:18.156585 kernel: printk: console [tty1] enabled Oct 2 19:36:18.156600 kernel: ACPI: Core revision 20210730 Oct 2 19:36:18.156616 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 166.66 BogoMIPS (lpj=83333) Oct 2 19:36:18.156631 kernel: pid_max: default: 32768 minimum: 301 Oct 2 19:36:18.156647 kernel: LSM: Security Framework initializing Oct 2 19:36:18.156662 kernel: SELinux: Initializing. Oct 2 19:36:18.156677 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 2 19:36:18.156697 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 2 19:36:18.156712 kernel: rcu: Hierarchical SRCU implementation. Oct 2 19:36:18.156727 kernel: Platform MSI: ITS@0x10080000 domain created Oct 2 19:36:18.156743 kernel: PCI/MSI: ITS@0x10080000 domain created Oct 2 19:36:18.156758 kernel: Remapping and enabling EFI services. Oct 2 19:36:18.156773 kernel: smp: Bringing up secondary CPUs ... Oct 2 19:36:18.156789 kernel: Detected PIPT I-cache on CPU1 Oct 2 19:36:18.156804 kernel: GICv3: CPU1: found redistributor 1 region 0:0x0000000010220000 Oct 2 19:36:18.156820 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000004000e0000 Oct 2 19:36:18.156839 kernel: CPU1: Booted secondary processor 0x0000000001 [0x410fd083] Oct 2 19:36:18.156855 kernel: smp: Brought up 1 node, 2 CPUs Oct 2 19:36:18.156870 kernel: SMP: Total of 2 processors activated. Oct 2 19:36:18.156885 kernel: CPU features: detected: 32-bit EL0 Support Oct 2 19:36:18.156900 kernel: CPU features: detected: 32-bit EL1 Support Oct 2 19:36:18.156915 kernel: CPU features: detected: CRC32 instructions Oct 2 19:36:18.156930 kernel: CPU: All CPU(s) started at EL1 Oct 2 19:36:18.156946 kernel: alternatives: patching kernel code Oct 2 19:36:18.156961 kernel: devtmpfs: initialized Oct 2 19:36:18.156979 kernel: KASLR disabled due to lack of seed Oct 2 19:36:18.156996 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 2 19:36:18.157012 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Oct 2 19:36:18.157051 kernel: pinctrl core: initialized pinctrl subsystem Oct 2 19:36:18.157077 kernel: SMBIOS 3.0.0 present. Oct 2 19:36:18.157094 kernel: DMI: Amazon EC2 a1.large/, BIOS 1.0 11/1/2018 Oct 2 19:36:18.157110 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Oct 2 19:36:18.157127 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 2 19:36:18.157143 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 2 19:36:18.157159 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 2 19:36:18.157175 kernel: audit: initializing netlink subsys (disabled) Oct 2 19:36:18.157191 kernel: audit: type=2000 audit(0.249:1): state=initialized audit_enabled=0 res=1 Oct 2 19:36:18.157212 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 2 19:36:18.157228 kernel: cpuidle: using governor menu Oct 2 19:36:18.157244 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 2 19:36:18.157260 kernel: ASID allocator initialised with 32768 entries Oct 2 19:36:18.157276 kernel: ACPI: bus type PCI registered Oct 2 19:36:18.157296 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 2 19:36:18.157312 kernel: Serial: AMBA PL011 UART driver Oct 2 19:36:18.157328 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Oct 2 19:36:18.157344 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Oct 2 19:36:18.157360 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Oct 2 19:36:18.157376 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Oct 2 19:36:18.157392 kernel: cryptd: max_cpu_qlen set to 1000 Oct 2 19:36:18.157408 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Oct 2 19:36:18.157424 kernel: ACPI: Added _OSI(Module Device) Oct 2 19:36:18.157444 kernel: ACPI: Added _OSI(Processor Device) Oct 2 19:36:18.157460 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Oct 2 19:36:18.157476 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 2 19:36:18.157492 kernel: ACPI: Added _OSI(Linux-Dell-Video) Oct 2 19:36:18.157508 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Oct 2 19:36:18.157524 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Oct 2 19:36:18.157540 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 2 19:36:18.157555 kernel: ACPI: Interpreter enabled Oct 2 19:36:18.157572 kernel: ACPI: Using GIC for interrupt routing Oct 2 19:36:18.157591 kernel: ACPI: MCFG table detected, 1 entries Oct 2 19:36:18.157608 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-0f]) Oct 2 19:36:18.158026 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 2 19:36:18.158250 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 2 19:36:18.158446 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 2 19:36:18.158633 kernel: acpi PNP0A08:00: ECAM area [mem 0x20000000-0x20ffffff] reserved by PNP0C02:00 Oct 2 19:36:18.158826 kernel: acpi PNP0A08:00: ECAM at [mem 0x20000000-0x20ffffff] for [bus 00-0f] Oct 2 19:36:18.158854 kernel: ACPI: Remapped I/O 0x000000001fff0000 to [io 0x0000-0xffff window] Oct 2 19:36:18.158871 kernel: acpiphp: Slot [1] registered Oct 2 19:36:18.158888 kernel: acpiphp: Slot [2] registered Oct 2 19:36:18.158904 kernel: acpiphp: Slot [3] registered Oct 2 19:36:18.158920 kernel: acpiphp: Slot [4] registered Oct 2 19:36:18.158936 kernel: acpiphp: Slot [5] registered Oct 2 19:36:18.158951 kernel: acpiphp: Slot [6] registered Oct 2 19:36:18.158967 kernel: acpiphp: Slot [7] registered Oct 2 19:36:18.158983 kernel: acpiphp: Slot [8] registered Oct 2 19:36:18.159002 kernel: acpiphp: Slot [9] registered Oct 2 19:36:18.159019 kernel: acpiphp: Slot [10] registered Oct 2 19:36:18.159035 kernel: acpiphp: Slot [11] registered Oct 2 19:36:18.159083 kernel: acpiphp: Slot [12] registered Oct 2 19:36:18.159100 kernel: acpiphp: Slot [13] registered Oct 2 19:36:18.159116 kernel: acpiphp: Slot [14] registered Oct 2 19:36:18.159132 kernel: acpiphp: Slot [15] registered Oct 2 19:36:18.159148 kernel: acpiphp: Slot [16] registered Oct 2 19:36:18.159164 kernel: acpiphp: Slot [17] registered Oct 2 19:36:18.159180 kernel: acpiphp: Slot [18] registered Oct 2 19:36:18.159201 kernel: acpiphp: Slot [19] registered Oct 2 19:36:18.159217 kernel: acpiphp: Slot [20] registered Oct 2 19:36:18.159233 kernel: acpiphp: Slot [21] registered Oct 2 19:36:18.159248 kernel: acpiphp: Slot [22] registered Oct 2 19:36:18.159264 kernel: acpiphp: Slot [23] registered Oct 2 19:36:18.159280 kernel: acpiphp: Slot [24] registered Oct 2 19:36:18.159296 kernel: acpiphp: Slot [25] registered Oct 2 19:36:18.159312 kernel: acpiphp: Slot [26] registered Oct 2 19:36:18.159327 kernel: acpiphp: Slot [27] registered Oct 2 19:36:18.159347 kernel: acpiphp: Slot [28] registered Oct 2 19:36:18.159363 kernel: acpiphp: Slot [29] registered Oct 2 19:36:18.159379 kernel: acpiphp: Slot [30] registered Oct 2 19:36:18.159395 kernel: acpiphp: Slot [31] registered Oct 2 19:36:18.159411 kernel: PCI host bridge to bus 0000:00 Oct 2 19:36:18.159609 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xffffffff window] Oct 2 19:36:18.159808 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 2 19:36:18.159989 kernel: pci_bus 0000:00: root bus resource [mem 0x400000000000-0x407fffffffff window] Oct 2 19:36:18.160194 kernel: pci_bus 0000:00: root bus resource [bus 00-0f] Oct 2 19:36:18.160417 kernel: pci 0000:00:00.0: [1d0f:0200] type 00 class 0x060000 Oct 2 19:36:18.160627 kernel: pci 0000:00:01.0: [1d0f:8250] type 00 class 0x070003 Oct 2 19:36:18.160828 kernel: pci 0000:00:01.0: reg 0x10: [mem 0x80118000-0x80118fff] Oct 2 19:36:18.161054 kernel: pci 0000:00:04.0: [1d0f:8061] type 00 class 0x010802 Oct 2 19:36:18.161262 kernel: pci 0000:00:04.0: reg 0x10: [mem 0x80114000-0x80117fff] Oct 2 19:36:18.161465 kernel: pci 0000:00:04.0: PME# supported from D0 D1 D2 D3hot D3cold Oct 2 19:36:18.161674 kernel: pci 0000:00:05.0: [1d0f:ec20] type 00 class 0x020000 Oct 2 19:36:18.161872 kernel: pci 0000:00:05.0: reg 0x10: [mem 0x80110000-0x80113fff] Oct 2 19:36:18.162107 kernel: pci 0000:00:05.0: reg 0x18: [mem 0x80000000-0x800fffff pref] Oct 2 19:36:18.162306 kernel: pci 0000:00:05.0: reg 0x20: [mem 0x80100000-0x8010ffff] Oct 2 19:36:18.162503 kernel: pci 0000:00:05.0: PME# supported from D0 D1 D2 D3hot D3cold Oct 2 19:36:18.162716 kernel: pci 0000:00:05.0: BAR 2: assigned [mem 0x80000000-0x800fffff pref] Oct 2 19:36:18.162919 kernel: pci 0000:00:05.0: BAR 4: assigned [mem 0x80100000-0x8010ffff] Oct 2 19:36:18.163150 kernel: pci 0000:00:04.0: BAR 0: assigned [mem 0x80110000-0x80113fff] Oct 2 19:36:18.163349 kernel: pci 0000:00:05.0: BAR 0: assigned [mem 0x80114000-0x80117fff] Oct 2 19:36:18.163549 kernel: pci 0000:00:01.0: BAR 0: assigned [mem 0x80118000-0x80118fff] Oct 2 19:36:18.163722 kernel: pci_bus 0000:00: resource 4 [mem 0x80000000-0xffffffff window] Oct 2 19:36:18.163898 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 2 19:36:18.164097 kernel: pci_bus 0000:00: resource 6 [mem 0x400000000000-0x407fffffffff window] Oct 2 19:36:18.164125 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Oct 2 19:36:18.164143 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Oct 2 19:36:18.164160 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Oct 2 19:36:18.164176 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Oct 2 19:36:18.164192 kernel: iommu: Default domain type: Translated Oct 2 19:36:18.164208 kernel: iommu: DMA domain TLB invalidation policy: strict mode Oct 2 19:36:18.164224 kernel: vgaarb: loaded Oct 2 19:36:18.164241 kernel: pps_core: LinuxPPS API ver. 1 registered Oct 2 19:36:18.164275 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Oct 2 19:36:18.164297 kernel: PTP clock support registered Oct 2 19:36:18.164314 kernel: Registered efivars operations Oct 2 19:36:18.164330 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 2 19:36:18.164346 kernel: VFS: Disk quotas dquot_6.6.0 Oct 2 19:36:18.164362 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 2 19:36:18.164378 kernel: pnp: PnP ACPI init Oct 2 19:36:18.164613 kernel: system 00:00: [mem 0x20000000-0x2fffffff] could not be reserved Oct 2 19:36:18.164639 kernel: pnp: PnP ACPI: found 1 devices Oct 2 19:36:18.164656 kernel: NET: Registered PF_INET protocol family Oct 2 19:36:18.164677 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 2 19:36:18.164694 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 2 19:36:18.164711 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 2 19:36:18.164727 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 2 19:36:18.164743 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Oct 2 19:36:18.164760 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 2 19:36:18.164776 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 2 19:36:18.164793 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 2 19:36:18.164809 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Oct 2 19:36:18.164830 kernel: PCI: CLS 0 bytes, default 64 Oct 2 19:36:18.164846 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 3 counters available Oct 2 19:36:18.164862 kernel: kvm [1]: HYP mode not available Oct 2 19:36:18.164879 kernel: Initialise system trusted keyrings Oct 2 19:36:18.164895 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 2 19:36:18.164912 kernel: Key type asymmetric registered Oct 2 19:36:18.164928 kernel: Asymmetric key parser 'x509' registered Oct 2 19:36:18.164944 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Oct 2 19:36:18.164960 kernel: io scheduler mq-deadline registered Oct 2 19:36:18.164980 kernel: io scheduler kyber registered Oct 2 19:36:18.164996 kernel: io scheduler bfq registered Oct 2 19:36:18.165224 kernel: pl061_gpio ARMH0061:00: PL061 GPIO chip registered Oct 2 19:36:18.165250 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 2 19:36:18.165267 kernel: ACPI: button: Power Button [PWRB] Oct 2 19:36:18.165284 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 2 19:36:18.165301 kernel: ACPI: \_SB_.PCI0.GSI2: Enabled at IRQ 37 Oct 2 19:36:18.165563 kernel: serial 0000:00:01.0: enabling device (0010 -> 0012) Oct 2 19:36:18.165601 kernel: printk: console [ttyS0] disabled Oct 2 19:36:18.165619 kernel: 0000:00:01.0: ttyS0 at MMIO 0x80118000 (irq = 14, base_baud = 115200) is a 16550A Oct 2 19:36:18.165635 kernel: printk: console [ttyS0] enabled Oct 2 19:36:18.165652 kernel: printk: bootconsole [uart0] disabled Oct 2 19:36:18.165668 kernel: thunder_xcv, ver 1.0 Oct 2 19:36:18.165684 kernel: thunder_bgx, ver 1.0 Oct 2 19:36:18.165701 kernel: nicpf, ver 1.0 Oct 2 19:36:18.165717 kernel: nicvf, ver 1.0 Oct 2 19:36:18.165953 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 2 19:36:18.166288 kernel: rtc-efi rtc-efi.0: setting system clock to 2023-10-02T19:36:17 UTC (1696275377) Oct 2 19:36:18.166319 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 2 19:36:18.166336 kernel: NET: Registered PF_INET6 protocol family Oct 2 19:36:18.166353 kernel: Segment Routing with IPv6 Oct 2 19:36:18.166369 kernel: In-situ OAM (IOAM) with IPv6 Oct 2 19:36:18.166385 kernel: NET: Registered PF_PACKET protocol family Oct 2 19:36:18.166401 kernel: Key type dns_resolver registered Oct 2 19:36:18.166417 kernel: registered taskstats version 1 Oct 2 19:36:18.166440 kernel: Loading compiled-in X.509 certificates Oct 2 19:36:18.166457 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.15.132-flatcar: 3a2a38edc68cb70dc60ec0223a6460557b3bb28d' Oct 2 19:36:18.166473 kernel: Key type .fscrypt registered Oct 2 19:36:18.166489 kernel: Key type fscrypt-provisioning registered Oct 2 19:36:18.166570 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 2 19:36:18.166595 kernel: ima: Allocated hash algorithm: sha1 Oct 2 19:36:18.166612 kernel: ima: No architecture policies found Oct 2 19:36:18.166628 kernel: Freeing unused kernel memory: 34560K Oct 2 19:36:18.166644 kernel: Run /init as init process Oct 2 19:36:18.166665 kernel: with arguments: Oct 2 19:36:18.166681 kernel: /init Oct 2 19:36:18.166697 kernel: with environment: Oct 2 19:36:18.166713 kernel: HOME=/ Oct 2 19:36:18.166729 kernel: TERM=linux Oct 2 19:36:18.166745 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 2 19:36:18.166767 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) Oct 2 19:36:18.166789 systemd[1]: Detected virtualization amazon. Oct 2 19:36:18.166812 systemd[1]: Detected architecture arm64. Oct 2 19:36:18.166829 systemd[1]: Running in initrd. Oct 2 19:36:18.166847 systemd[1]: No hostname configured, using default hostname. Oct 2 19:36:18.166864 systemd[1]: Hostname set to . Oct 2 19:36:18.166882 systemd[1]: Initializing machine ID from VM UUID. Oct 2 19:36:18.166900 systemd[1]: Queued start job for default target initrd.target. Oct 2 19:36:18.166919 systemd[1]: Started systemd-ask-password-console.path. Oct 2 19:36:18.166937 systemd[1]: Reached target cryptsetup.target. Oct 2 19:36:18.166959 systemd[1]: Reached target paths.target. Oct 2 19:36:18.166977 systemd[1]: Reached target slices.target. Oct 2 19:36:18.166994 systemd[1]: Reached target swap.target. Oct 2 19:36:18.167012 systemd[1]: Reached target timers.target. Oct 2 19:36:18.167030 systemd[1]: Listening on iscsid.socket. Oct 2 19:36:18.167205 systemd[1]: Listening on iscsiuio.socket. Oct 2 19:36:18.167225 systemd[1]: Listening on systemd-journald-audit.socket. Oct 2 19:36:18.167243 systemd[1]: Listening on systemd-journald-dev-log.socket. Oct 2 19:36:18.167267 systemd[1]: Listening on systemd-journald.socket. Oct 2 19:36:18.167285 systemd[1]: Listening on systemd-networkd.socket. Oct 2 19:36:18.167303 systemd[1]: Listening on systemd-udevd-control.socket. Oct 2 19:36:18.167321 systemd[1]: Listening on systemd-udevd-kernel.socket. Oct 2 19:36:18.167339 systemd[1]: Reached target sockets.target. Oct 2 19:36:18.167357 systemd[1]: Starting kmod-static-nodes.service... Oct 2 19:36:18.167374 systemd[1]: Finished network-cleanup.service. Oct 2 19:36:18.167392 systemd[1]: Starting systemd-fsck-usr.service... Oct 2 19:36:18.167410 systemd[1]: Starting systemd-journald.service... Oct 2 19:36:18.167432 systemd[1]: Starting systemd-modules-load.service... Oct 2 19:36:18.167449 systemd[1]: Starting systemd-resolved.service... Oct 2 19:36:18.167467 systemd[1]: Starting systemd-vconsole-setup.service... Oct 2 19:36:18.167485 systemd[1]: Finished kmod-static-nodes.service. Oct 2 19:36:18.167502 systemd[1]: Finished systemd-fsck-usr.service. Oct 2 19:36:18.167520 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... Oct 2 19:36:18.167538 systemd[1]: Finished systemd-vconsole-setup.service. Oct 2 19:36:18.167556 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Oct 2 19:36:18.167574 kernel: audit: type=1130 audit(1696275378.156:2): 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' Oct 2 19:36:18.167596 systemd[1]: Starting dracut-cmdline-ask.service... Oct 2 19:36:18.167618 systemd-journald[308]: Journal started Oct 2 19:36:18.167710 systemd-journald[308]: Runtime Journal (/run/log/journal/ec2fc7b1f3c281e1983e7d6efb389207) is 8.0M, max 75.4M, 67.4M free. Oct 2 19:36:18.156000 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' Oct 2 19:36:18.126131 systemd-modules-load[309]: Inserted module 'overlay' Oct 2 19:36:18.182259 systemd[1]: Started systemd-journald.service. Oct 2 19:36:18.184000 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' Oct 2 19:36:18.199602 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. Oct 2 19:36:18.208396 kernel: audit: type=1130 audit(1696275378.184:3): 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' Oct 2 19:36:18.208434 kernel: Bridge firewalling registered Oct 2 19:36:18.199964 systemd-modules-load[309]: Inserted module 'br_netfilter' Oct 2 19:36:18.220391 kernel: audit: type=1130 audit(1696275378.198:4): 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' Oct 2 19:36:18.198000 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' Oct 2 19:36:18.238082 kernel: SCSI subsystem initialized Oct 2 19:36:18.255919 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Oct 2 19:36:18.255985 kernel: device-mapper: uevent: version 1.0.3 Oct 2 19:36:18.259209 kernel: device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com Oct 2 19:36:18.262777 systemd-resolved[310]: Positive Trust Anchors: Oct 2 19:36:18.266703 systemd-resolved[310]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 2 19:36:18.266764 systemd-resolved[310]: 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 Oct 2 19:36:18.306167 systemd-modules-load[309]: Inserted module 'dm_multipath' Oct 2 19:36:18.311729 systemd[1]: Finished dracut-cmdline-ask.service. Oct 2 19:36:18.333202 kernel: audit: type=1130 audit(1696275378.312:5): 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' Oct 2 19:36:18.312000 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' Oct 2 19:36:18.314015 systemd[1]: Finished systemd-modules-load.service. Oct 2 19:36:18.322000 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' Oct 2 19:36:18.324753 systemd[1]: Starting dracut-cmdline.service... Oct 2 19:36:18.348703 systemd[1]: Starting systemd-sysctl.service... Oct 2 19:36:18.371072 kernel: audit: type=1130 audit(1696275378.322:6): 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' Oct 2 19:36:18.384914 systemd[1]: Finished systemd-sysctl.service. Oct 2 19:36:18.386000 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' Oct 2 19:36:18.399070 kernel: audit: type=1130 audit(1696275378.386:7): 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' Oct 2 19:36:18.402113 dracut-cmdline[330]: dracut-dracut-053 Oct 2 19:36:18.412961 dracut-cmdline[330]: 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=684fe6a2259d7fb96810743ab87aaaa03d9f185b113bd6990a64d1079e5672ca Oct 2 19:36:18.644075 kernel: Loading iSCSI transport class v2.0-870. Oct 2 19:36:18.658111 kernel: iscsi: registered transport (tcp) Oct 2 19:36:18.684532 kernel: iscsi: registered transport (qla4xxx) Oct 2 19:36:18.684611 kernel: QLogic iSCSI HBA Driver Oct 2 19:36:18.863066 kernel: random: crng init done Oct 2 19:36:18.863158 systemd-resolved[310]: Defaulting to hostname 'linux'. Oct 2 19:36:18.867463 systemd[1]: Started systemd-resolved.service. Oct 2 19:36:18.880130 kernel: audit: type=1130 audit(1696275378.868:8): 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' Oct 2 19:36:18.868000 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' Oct 2 19:36:18.869413 systemd[1]: Reached target nss-lookup.target. Oct 2 19:36:18.923589 systemd[1]: Finished dracut-cmdline.service. Oct 2 19:36:18.925000 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' Oct 2 19:36:18.928634 systemd[1]: Starting dracut-pre-udev.service... Oct 2 19:36:18.938052 kernel: audit: type=1130 audit(1696275378.925:9): 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' Oct 2 19:36:19.023079 kernel: raid6: neonx8 gen() 6361 MB/s Oct 2 19:36:19.041072 kernel: raid6: neonx8 xor() 4746 MB/s Oct 2 19:36:19.059070 kernel: raid6: neonx4 gen() 6433 MB/s Oct 2 19:36:19.077071 kernel: raid6: neonx4 xor() 4931 MB/s Oct 2 19:36:19.095070 kernel: raid6: neonx2 gen() 5742 MB/s Oct 2 19:36:19.113071 kernel: raid6: neonx2 xor() 4537 MB/s Oct 2 19:36:19.131072 kernel: raid6: neonx1 gen() 4458 MB/s Oct 2 19:36:19.149071 kernel: raid6: neonx1 xor() 3670 MB/s Oct 2 19:36:19.167071 kernel: raid6: int64x8 gen() 3405 MB/s Oct 2 19:36:19.185070 kernel: raid6: int64x8 xor() 2094 MB/s Oct 2 19:36:19.203071 kernel: raid6: int64x4 gen() 3777 MB/s Oct 2 19:36:19.221070 kernel: raid6: int64x4 xor() 2200 MB/s Oct 2 19:36:19.239071 kernel: raid6: int64x2 gen() 3588 MB/s Oct 2 19:36:19.257070 kernel: raid6: int64x2 xor() 1951 MB/s Oct 2 19:36:19.275071 kernel: raid6: int64x1 gen() 2764 MB/s Oct 2 19:36:19.294655 kernel: raid6: int64x1 xor() 1453 MB/s Oct 2 19:36:19.294685 kernel: raid6: using algorithm neonx4 gen() 6433 MB/s Oct 2 19:36:19.294708 kernel: raid6: .... xor() 4931 MB/s, rmw enabled Oct 2 19:36:19.296527 kernel: raid6: using neon recovery algorithm Oct 2 19:36:19.315078 kernel: xor: measuring software checksum speed Oct 2 19:36:19.318073 kernel: 8regs : 9333 MB/sec Oct 2 19:36:19.320071 kernel: 32regs : 11107 MB/sec Oct 2 19:36:19.324415 kernel: arm64_neon : 9632 MB/sec Oct 2 19:36:19.324446 kernel: xor: using function: 32regs (11107 MB/sec) Oct 2 19:36:19.413086 kernel: Btrfs loaded, crc32c=crc32c-generic, zoned=no, fsverity=no Oct 2 19:36:19.451419 systemd[1]: Finished dracut-pre-udev.service. Oct 2 19:36:19.452000 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' Oct 2 19:36:19.461000 audit: BPF prog-id=7 op=LOAD Oct 2 19:36:19.461000 audit: BPF prog-id=8 op=LOAD Oct 2 19:36:19.463128 kernel: audit: type=1130 audit(1696275379.452:10): 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' Oct 2 19:36:19.462913 systemd[1]: Starting systemd-udevd.service... Oct 2 19:36:19.500176 systemd-udevd[509]: Using default interface naming scheme 'v252'. Oct 2 19:36:19.511191 systemd[1]: Started systemd-udevd.service. Oct 2 19:36:19.511000 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' Oct 2 19:36:19.520971 systemd[1]: Starting dracut-pre-trigger.service... Oct 2 19:36:19.579993 dracut-pre-trigger[522]: rd.md=0: removing MD RAID activation Oct 2 19:36:19.687246 systemd[1]: Finished dracut-pre-trigger.service. Oct 2 19:36:19.688000 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' Oct 2 19:36:19.691762 systemd[1]: Starting systemd-udev-trigger.service... Oct 2 19:36:19.807671 systemd[1]: Finished systemd-udev-trigger.service. Oct 2 19:36:19.808000 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' Oct 2 19:36:19.946694 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Oct 2 19:36:19.946758 kernel: ena 0000:00:05.0: enabling device (0010 -> 0012) Oct 2 19:36:19.960614 kernel: ena 0000:00:05.0: ENA device version: 0.10 Oct 2 19:36:19.960916 kernel: ena 0000:00:05.0: ENA controller version: 0.0.1 implementation version 1 Oct 2 19:36:19.970066 kernel: ena 0000:00:05.0: Elastic Network Adapter (ENA) found at mem 80114000, mac addr 06:0a:66:7f:40:13 Oct 2 19:36:19.973007 (udev-worker)[577]: Network interface NamePolicy= disabled on kernel command line. Oct 2 19:36:19.984186 kernel: ACPI: \_SB_.PCI0.GSI0: Enabled at IRQ 35 Oct 2 19:36:19.984266 kernel: nvme nvme0: pci function 0000:00:04.0 Oct 2 19:36:19.993143 kernel: nvme nvme0: 2/0/0 default/read/poll queues Oct 2 19:36:19.999414 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Oct 2 19:36:19.999469 kernel: GPT:9289727 != 16777215 Oct 2 19:36:19.999493 kernel: GPT:Alternate GPT header not at the end of the disk. Oct 2 19:36:20.003601 kernel: GPT:9289727 != 16777215 Oct 2 19:36:20.003647 kernel: GPT: Use GNU Parted to correct GPT errors. Oct 2 19:36:20.007123 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Oct 2 19:36:20.195081 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/nvme0n1p6 scanned by (udev-worker) (575) Oct 2 19:36:20.213249 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device. Oct 2 19:36:20.273031 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Oct 2 19:36:20.433725 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device. Oct 2 19:36:20.446018 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device. Oct 2 19:36:20.480210 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device. Oct 2 19:36:20.485474 systemd[1]: Starting disk-uuid.service... Oct 2 19:36:20.506810 disk-uuid[674]: Primary Header is updated. Oct 2 19:36:20.506810 disk-uuid[674]: Secondary Entries is updated. Oct 2 19:36:20.506810 disk-uuid[674]: Secondary Header is updated. Oct 2 19:36:20.515072 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Oct 2 19:36:21.527785 disk-uuid[675]: The operation has completed successfully. Oct 2 19:36:21.530163 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Oct 2 19:36:21.799305 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 2 19:36:21.799854 systemd[1]: Finished disk-uuid.service. Oct 2 19:36:21.816057 kernel: kauditd_printk_skb: 5 callbacks suppressed Oct 2 19:36:21.816116 kernel: audit: type=1130 audit(1696275381.804:16): 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' Oct 2 19:36:21.804000 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' Oct 2 19:36:21.805000 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' Oct 2 19:36:21.823362 kernel: audit: type=1131 audit(1696275381.805:17): 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' Oct 2 19:36:21.817672 systemd[1]: Starting verity-setup.service... Oct 2 19:36:21.892067 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Oct 2 19:36:22.007399 systemd[1]: Found device dev-mapper-usr.device. Oct 2 19:36:22.012574 systemd[1]: Mounting sysusr-usr.mount... Oct 2 19:36:22.022153 systemd[1]: Finished verity-setup.service. Oct 2 19:36:22.023000 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' Oct 2 19:36:22.034086 kernel: audit: type=1130 audit(1696275382.023:18): 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' Oct 2 19:36:22.118083 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: norecovery. Quota mode: none. Oct 2 19:36:22.120142 systemd[1]: Mounted sysusr-usr.mount. Oct 2 19:36:22.120821 systemd[1]: afterburn-network-kargs.service was skipped because no trigger condition checks were met. Oct 2 19:36:22.124830 systemd[1]: Starting ignition-setup.service... Oct 2 19:36:22.139546 systemd[1]: Starting parse-ip-for-networkd.service... Oct 2 19:36:22.165467 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Oct 2 19:36:22.165534 kernel: BTRFS info (device nvme0n1p6): using free space tree Oct 2 19:36:22.167767 kernel: BTRFS info (device nvme0n1p6): has skinny extents Oct 2 19:36:22.209082 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Oct 2 19:36:22.243501 systemd[1]: mnt-oem.mount: Deactivated successfully. Oct 2 19:36:22.359604 systemd[1]: Finished ignition-setup.service. Oct 2 19:36:22.361000 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' Oct 2 19:36:22.371545 systemd[1]: Starting ignition-fetch-offline.service... Oct 2 19:36:22.379786 kernel: audit: type=1130 audit(1696275382.361:19): 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' Oct 2 19:36:22.494237 systemd[1]: Finished parse-ip-for-networkd.service. Oct 2 19:36:22.496000 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' Oct 2 19:36:22.504000 audit: BPF prog-id=9 op=LOAD Oct 2 19:36:22.508149 kernel: audit: type=1130 audit(1696275382.496:20): 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' Oct 2 19:36:22.508203 kernel: audit: type=1334 audit(1696275382.504:21): prog-id=9 op=LOAD Oct 2 19:36:22.506518 systemd[1]: Starting systemd-networkd.service... Oct 2 19:36:22.562889 systemd-networkd[1199]: lo: Link UP Oct 2 19:36:22.562912 systemd-networkd[1199]: lo: Gained carrier Oct 2 19:36:22.566881 systemd-networkd[1199]: Enumeration completed Oct 2 19:36:22.568000 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' Oct 2 19:36:22.567409 systemd-networkd[1199]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 2 19:36:22.583485 kernel: audit: type=1130 audit(1696275382.568:22): 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' Oct 2 19:36:22.567577 systemd[1]: Started systemd-networkd.service. Oct 2 19:36:22.569534 systemd[1]: Reached target network.target. Oct 2 19:36:22.585316 systemd[1]: Starting iscsiuio.service... Oct 2 19:36:22.602837 systemd-networkd[1199]: eth0: Link UP Oct 2 19:36:22.604416 systemd-networkd[1199]: eth0: Gained carrier Oct 2 19:36:22.606524 systemd[1]: Started iscsiuio.service. Oct 2 19:36:22.607000 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' Oct 2 19:36:22.617618 systemd[1]: Starting iscsid.service... Oct 2 19:36:22.626078 kernel: audit: type=1130 audit(1696275382.607:23): 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' Oct 2 19:36:22.628295 systemd-networkd[1199]: eth0: DHCPv4 address 172.31.17.106/20, gateway 172.31.16.1 acquired from 172.31.16.1 Oct 2 19:36:22.635226 iscsid[1204]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Oct 2 19:36:22.635226 iscsid[1204]: 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]. Oct 2 19:36:22.635226 iscsid[1204]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Oct 2 19:36:22.635226 iscsid[1204]: If using hardware iscsi like qla4xxx this message can be ignored. Oct 2 19:36:22.652821 iscsid[1204]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Oct 2 19:36:22.652821 iscsid[1204]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Oct 2 19:36:22.667754 systemd[1]: Started iscsid.service. Oct 2 19:36:22.691855 kernel: audit: type=1130 audit(1696275382.666:24): 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' Oct 2 19:36:22.666000 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' Oct 2 19:36:22.684569 systemd[1]: Starting dracut-initqueue.service... Oct 2 19:36:22.725022 systemd[1]: Finished dracut-initqueue.service. Oct 2 19:36:22.740089 kernel: audit: type=1130 audit(1696275382.724:25): 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' Oct 2 19:36:22.724000 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' Oct 2 19:36:22.725610 systemd[1]: Reached target remote-fs-pre.target. Oct 2 19:36:22.735952 systemd[1]: Reached target remote-cryptsetup.target. Oct 2 19:36:22.740120 systemd[1]: Reached target remote-fs.target. Oct 2 19:36:22.743599 systemd[1]: Starting dracut-pre-mount.service... Oct 2 19:36:22.777736 systemd[1]: Finished dracut-pre-mount.service. Oct 2 19:36:22.778000 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' Oct 2 19:36:23.804228 systemd-networkd[1199]: eth0: Gained IPv6LL Oct 2 19:36:24.737144 ignition[1155]: Ignition 2.14.0 Oct 2 19:36:24.737174 ignition[1155]: Stage: fetch-offline Oct 2 19:36:24.737620 ignition[1155]: reading system config file "/usr/lib/ignition/base.d/base.ign" Oct 2 19:36:24.738982 ignition[1155]: parsing config with SHA512: 6629d8e825d60c9c9d4629d8547ef9a0b839d6b01b7f61a481a1f23308c924b8b0bbf10cae7f7fe3bcaf88b23d1a81baa7771c3670728d4d2a1e665216a1de7b Oct 2 19:36:24.758837 ignition[1155]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Oct 2 19:36:24.761915 ignition[1155]: Ignition finished successfully Oct 2 19:36:24.765219 systemd[1]: Finished ignition-fetch-offline.service. Oct 2 19:36:24.766000 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' Oct 2 19:36:24.770675 systemd[1]: Starting ignition-fetch.service... Oct 2 19:36:24.800210 ignition[1223]: Ignition 2.14.0 Oct 2 19:36:24.800691 ignition[1223]: Stage: fetch Oct 2 19:36:24.801084 ignition[1223]: reading system config file "/usr/lib/ignition/base.d/base.ign" Oct 2 19:36:24.801144 ignition[1223]: parsing config with SHA512: 6629d8e825d60c9c9d4629d8547ef9a0b839d6b01b7f61a481a1f23308c924b8b0bbf10cae7f7fe3bcaf88b23d1a81baa7771c3670728d4d2a1e665216a1de7b Oct 2 19:36:24.816219 ignition[1223]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Oct 2 19:36:24.818829 ignition[1223]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Oct 2 19:36:24.843466 ignition[1223]: INFO : PUT result: OK Oct 2 19:36:24.847138 ignition[1223]: DEBUG : parsed url from cmdline: "" Oct 2 19:36:24.847138 ignition[1223]: INFO : no config URL provided Oct 2 19:36:24.850707 ignition[1223]: INFO : reading system config file "/usr/lib/ignition/user.ign" Oct 2 19:36:24.850707 ignition[1223]: INFO : no config at "/usr/lib/ignition/user.ign" Oct 2 19:36:24.850707 ignition[1223]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Oct 2 19:36:24.857837 ignition[1223]: INFO : PUT result: OK Oct 2 19:36:24.857837 ignition[1223]: INFO : GET http://169.254.169.254/2019-10-01/user-data: attempt #1 Oct 2 19:36:24.861833 ignition[1223]: INFO : GET result: OK Oct 2 19:36:24.861833 ignition[1223]: DEBUG : parsing config with SHA512: 364251a33315d31c5037365571197041973796667d3bc065b43c3ce83b0c183fb1ee9f800eeb578be1b25c20821e4f3ed471afb6e8315fa822580a44d8a16636 Oct 2 19:36:24.875231 unknown[1223]: fetched base config from "system" Oct 2 19:36:24.875270 unknown[1223]: fetched base config from "system" Oct 2 19:36:24.875295 unknown[1223]: fetched user config from "aws" Oct 2 19:36:24.880983 ignition[1223]: fetch: fetch complete Oct 2 19:36:24.881009 ignition[1223]: fetch: fetch passed Oct 2 19:36:24.881126 ignition[1223]: Ignition finished successfully Oct 2 19:36:24.887791 systemd[1]: Finished ignition-fetch.service. Oct 2 19:36:24.889000 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' Oct 2 19:36:24.892445 systemd[1]: Starting ignition-kargs.service... Oct 2 19:36:24.924724 ignition[1229]: Ignition 2.14.0 Oct 2 19:36:24.925245 ignition[1229]: Stage: kargs Oct 2 19:36:24.925605 ignition[1229]: reading system config file "/usr/lib/ignition/base.d/base.ign" Oct 2 19:36:24.925663 ignition[1229]: parsing config with SHA512: 6629d8e825d60c9c9d4629d8547ef9a0b839d6b01b7f61a481a1f23308c924b8b0bbf10cae7f7fe3bcaf88b23d1a81baa7771c3670728d4d2a1e665216a1de7b Oct 2 19:36:24.942481 ignition[1229]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Oct 2 19:36:24.945233 ignition[1229]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Oct 2 19:36:24.948254 ignition[1229]: INFO : PUT result: OK Oct 2 19:36:24.953461 ignition[1229]: kargs: kargs passed Oct 2 19:36:24.953609 ignition[1229]: Ignition finished successfully Oct 2 19:36:24.957973 systemd[1]: Finished ignition-kargs.service. Oct 2 19:36:24.958000 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' Oct 2 19:36:24.961835 systemd[1]: Starting ignition-disks.service... Oct 2 19:36:24.992390 ignition[1235]: Ignition 2.14.0 Oct 2 19:36:24.992419 ignition[1235]: Stage: disks Oct 2 19:36:24.992767 ignition[1235]: reading system config file "/usr/lib/ignition/base.d/base.ign" Oct 2 19:36:24.992830 ignition[1235]: parsing config with SHA512: 6629d8e825d60c9c9d4629d8547ef9a0b839d6b01b7f61a481a1f23308c924b8b0bbf10cae7f7fe3bcaf88b23d1a81baa7771c3670728d4d2a1e665216a1de7b Oct 2 19:36:25.008200 ignition[1235]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Oct 2 19:36:25.010925 ignition[1235]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Oct 2 19:36:25.014689 ignition[1235]: INFO : PUT result: OK Oct 2 19:36:25.019462 ignition[1235]: disks: disks passed Oct 2 19:36:25.019560 ignition[1235]: Ignition finished successfully Oct 2 19:36:25.021118 systemd[1]: Finished ignition-disks.service. Oct 2 19:36:25.028000 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' Oct 2 19:36:25.029671 systemd[1]: Reached target initrd-root-device.target. Oct 2 19:36:25.032799 systemd[1]: Reached target local-fs-pre.target. Oct 2 19:36:25.036143 systemd[1]: Reached target local-fs.target. Oct 2 19:36:25.039583 systemd[1]: Reached target sysinit.target. Oct 2 19:36:25.055546 systemd[1]: Reached target basic.target. Oct 2 19:36:25.062986 systemd[1]: Starting systemd-fsck-root.service... Oct 2 19:36:25.135852 systemd-fsck[1243]: ROOT: clean, 603/553520 files, 56011/553472 blocks Oct 2 19:36:25.147393 systemd[1]: Finished systemd-fsck-root.service. Oct 2 19:36:25.149000 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' Oct 2 19:36:25.152100 systemd[1]: Mounting sysroot.mount... Oct 2 19:36:25.183079 kernel: EXT4-fs (nvme0n1p9): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none. Oct 2 19:36:25.186370 systemd[1]: Mounted sysroot.mount. Oct 2 19:36:25.188014 systemd[1]: Reached target initrd-root-fs.target. Oct 2 19:36:25.200024 systemd[1]: Mounting sysroot-usr.mount... Oct 2 19:36:25.202337 systemd[1]: flatcar-metadata-hostname.service was skipped because no trigger condition checks were met. Oct 2 19:36:25.202444 systemd[1]: ignition-remount-sysroot.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Oct 2 19:36:25.202509 systemd[1]: Reached target ignition-diskful.target. Oct 2 19:36:25.230108 systemd[1]: Mounted sysroot-usr.mount. Oct 2 19:36:25.235959 systemd[1]: Mounting sysroot-usr-share-oem.mount... Oct 2 19:36:25.240819 systemd[1]: Starting initrd-setup-root.service... Oct 2 19:36:25.264071 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/nvme0n1p6 scanned by mount (1260) Oct 2 19:36:25.272334 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Oct 2 19:36:25.272406 kernel: BTRFS info (device nvme0n1p6): using free space tree Oct 2 19:36:25.274609 kernel: BTRFS info (device nvme0n1p6): has skinny extents Oct 2 19:36:25.282065 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Oct 2 19:36:25.286326 systemd[1]: Mounted sysroot-usr-share-oem.mount. Oct 2 19:36:25.297229 initrd-setup-root[1265]: cut: /sysroot/etc/passwd: No such file or directory Oct 2 19:36:25.341165 initrd-setup-root[1291]: cut: /sysroot/etc/group: No such file or directory Oct 2 19:36:25.360657 initrd-setup-root[1299]: cut: /sysroot/etc/shadow: No such file or directory Oct 2 19:36:25.379993 initrd-setup-root[1307]: cut: /sysroot/etc/gshadow: No such file or directory Oct 2 19:36:25.945525 systemd[1]: Finished initrd-setup-root.service. Oct 2 19:36:25.946000 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' Oct 2 19:36:25.950350 systemd[1]: Starting ignition-mount.service... Oct 2 19:36:25.955102 systemd[1]: Starting sysroot-boot.service... Oct 2 19:36:25.983605 systemd[1]: sysusr-usr-share-oem.mount: Deactivated successfully. Oct 2 19:36:25.983781 systemd[1]: sysroot-usr-share-oem.mount: Deactivated successfully. Oct 2 19:36:26.021787 systemd[1]: Finished sysroot-boot.service. Oct 2 19:36:26.024000 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' Oct 2 19:36:26.027152 ignition[1326]: INFO : Ignition 2.14.0 Oct 2 19:36:26.027152 ignition[1326]: INFO : Stage: mount Oct 2 19:36:26.030608 ignition[1326]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign" Oct 2 19:36:26.030608 ignition[1326]: DEBUG : parsing config with SHA512: 6629d8e825d60c9c9d4629d8547ef9a0b839d6b01b7f61a481a1f23308c924b8b0bbf10cae7f7fe3bcaf88b23d1a81baa7771c3670728d4d2a1e665216a1de7b Oct 2 19:36:26.046634 ignition[1326]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Oct 2 19:36:26.049461 ignition[1326]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Oct 2 19:36:26.053172 ignition[1326]: INFO : PUT result: OK Oct 2 19:36:26.057863 ignition[1326]: INFO : mount: mount passed Oct 2 19:36:26.059648 ignition[1326]: INFO : Ignition finished successfully Oct 2 19:36:26.063024 systemd[1]: Finished ignition-mount.service. Oct 2 19:36:26.063000 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' Oct 2 19:36:26.066252 systemd[1]: Starting ignition-files.service... Oct 2 19:36:26.089266 systemd[1]: Mounting sysroot-usr-share-oem.mount... Oct 2 19:36:26.113080 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/nvme0n1p6 scanned by mount (1335) Oct 2 19:36:26.118835 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Oct 2 19:36:26.118873 kernel: BTRFS info (device nvme0n1p6): using free space tree Oct 2 19:36:26.121069 kernel: BTRFS info (device nvme0n1p6): has skinny extents Oct 2 19:36:26.128063 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Oct 2 19:36:26.133324 systemd[1]: Mounted sysroot-usr-share-oem.mount. Oct 2 19:36:26.166152 ignition[1354]: INFO : Ignition 2.14.0 Oct 2 19:36:26.166152 ignition[1354]: INFO : Stage: files Oct 2 19:36:26.169607 ignition[1354]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign" Oct 2 19:36:26.169607 ignition[1354]: DEBUG : parsing config with SHA512: 6629d8e825d60c9c9d4629d8547ef9a0b839d6b01b7f61a481a1f23308c924b8b0bbf10cae7f7fe3bcaf88b23d1a81baa7771c3670728d4d2a1e665216a1de7b Oct 2 19:36:26.186222 ignition[1354]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Oct 2 19:36:26.188768 ignition[1354]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Oct 2 19:36:26.192188 ignition[1354]: INFO : PUT result: OK Oct 2 19:36:26.196880 ignition[1354]: DEBUG : files: compiled without relabeling support, skipping Oct 2 19:36:26.200766 ignition[1354]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Oct 2 19:36:26.200766 ignition[1354]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Oct 2 19:36:26.260594 ignition[1354]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Oct 2 19:36:26.263544 ignition[1354]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Oct 2 19:36:26.267427 unknown[1354]: wrote ssh authorized keys file for user: core Oct 2 19:36:26.269718 ignition[1354]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Oct 2 19:36:26.273411 ignition[1354]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/eks/bootstrap.sh" Oct 2 19:36:26.277108 ignition[1354]: INFO : oem config not found in "/usr/share/oem", looking on oem partition Oct 2 19:36:26.287742 ignition[1354]: INFO : op(1): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem913996098" Oct 2 19:36:26.294788 kernel: BTRFS info: devid 1 device path /dev/nvme0n1p6 changed to /dev/disk/by-label/OEM scanned by ignition (1359) Oct 2 19:36:26.294852 ignition[1354]: CRITICAL : op(1): [failed] mounting "/dev/disk/by-label/OEM" at "/mnt/oem913996098": device or resource busy Oct 2 19:36:26.294852 ignition[1354]: ERROR : failed to mount ext4 device "/dev/disk/by-label/OEM" at "/mnt/oem913996098", trying btrfs: device or resource busy Oct 2 19:36:26.294852 ignition[1354]: INFO : op(2): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem913996098" Oct 2 19:36:26.304681 ignition[1354]: INFO : op(2): [finished] mounting "/dev/disk/by-label/OEM" at "/mnt/oem913996098" Oct 2 19:36:26.364605 ignition[1354]: INFO : op(3): [started] unmounting "/mnt/oem913996098" Oct 2 19:36:26.367155 ignition[1354]: INFO : op(3): [finished] unmounting "/mnt/oem913996098" Oct 2 19:36:26.369633 ignition[1354]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/eks/bootstrap.sh" Oct 2 19:36:26.373192 ignition[1354]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/amazon/ssm/amazon-ssm-agent.json" Oct 2 19:36:26.373192 ignition[1354]: INFO : oem config not found in "/usr/share/oem", looking on oem partition Oct 2 19:36:26.381261 systemd[1]: mnt-oem913996098.mount: Deactivated successfully. Oct 2 19:36:26.394185 ignition[1354]: INFO : op(4): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem115817153" Oct 2 19:36:26.397228 ignition[1354]: CRITICAL : op(4): [failed] mounting "/dev/disk/by-label/OEM" at "/mnt/oem115817153": device or resource busy Oct 2 19:36:26.397228 ignition[1354]: ERROR : failed to mount ext4 device "/dev/disk/by-label/OEM" at "/mnt/oem115817153", trying btrfs: device or resource busy Oct 2 19:36:26.404151 ignition[1354]: INFO : op(5): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem115817153" Oct 2 19:36:26.406872 ignition[1354]: INFO : op(5): [finished] mounting "/dev/disk/by-label/OEM" at "/mnt/oem115817153" Oct 2 19:36:26.409998 ignition[1354]: INFO : op(6): [started] unmounting "/mnt/oem115817153" Oct 2 19:36:26.412402 ignition[1354]: INFO : op(6): [finished] unmounting "/mnt/oem115817153" Oct 2 19:36:26.414749 ignition[1354]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/amazon/ssm/amazon-ssm-agent.json" Oct 2 19:36:26.414749 ignition[1354]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing file "/sysroot/etc/amazon/ssm/seelog.xml" Oct 2 19:36:26.422276 ignition[1354]: INFO : oem config not found in "/usr/share/oem", looking on oem partition Oct 2 19:36:26.440577 ignition[1354]: INFO : op(7): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem3344635763" Oct 2 19:36:26.443668 ignition[1354]: CRITICAL : op(7): [failed] mounting "/dev/disk/by-label/OEM" at "/mnt/oem3344635763": device or resource busy Oct 2 19:36:26.443668 ignition[1354]: ERROR : failed to mount ext4 device "/dev/disk/by-label/OEM" at "/mnt/oem3344635763", trying btrfs: device or resource busy Oct 2 19:36:26.443668 ignition[1354]: INFO : op(8): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem3344635763" Oct 2 19:36:26.443668 ignition[1354]: INFO : op(8): [finished] mounting "/dev/disk/by-label/OEM" at "/mnt/oem3344635763" Oct 2 19:36:26.443668 ignition[1354]: INFO : op(9): [started] unmounting "/mnt/oem3344635763" Oct 2 19:36:26.458915 ignition[1354]: INFO : op(9): [finished] unmounting "/mnt/oem3344635763" Oct 2 19:36:26.458915 ignition[1354]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing file "/sysroot/etc/amazon/ssm/seelog.xml" Oct 2 19:36:26.458915 ignition[1354]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/etc/systemd/system/nvidia.service" Oct 2 19:36:26.468661 ignition[1354]: INFO : oem config not found in "/usr/share/oem", looking on oem partition Oct 2 19:36:26.485531 ignition[1354]: INFO : op(a): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem1601148741" Oct 2 19:36:26.485531 ignition[1354]: CRITICAL : op(a): [failed] mounting "/dev/disk/by-label/OEM" at "/mnt/oem1601148741": device or resource busy Oct 2 19:36:26.485531 ignition[1354]: ERROR : failed to mount ext4 device "/dev/disk/by-label/OEM" at "/mnt/oem1601148741", trying btrfs: device or resource busy Oct 2 19:36:26.485531 ignition[1354]: INFO : op(b): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem1601148741" Oct 2 19:36:26.485531 ignition[1354]: INFO : op(b): [finished] mounting "/dev/disk/by-label/OEM" at "/mnt/oem1601148741" Oct 2 19:36:26.485531 ignition[1354]: INFO : op(c): [started] unmounting "/mnt/oem1601148741" Oct 2 19:36:26.485531 ignition[1354]: INFO : op(c): [finished] unmounting "/mnt/oem1601148741" Oct 2 19:36:26.485531 ignition[1354]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/etc/systemd/system/nvidia.service" Oct 2 19:36:26.485531 ignition[1354]: INFO : files: op(7): [started] processing unit "coreos-metadata-sshkeys@.service" Oct 2 19:36:26.485531 ignition[1354]: INFO : files: op(7): [finished] processing unit "coreos-metadata-sshkeys@.service" Oct 2 19:36:26.485531 ignition[1354]: INFO : files: op(8): [started] processing unit "amazon-ssm-agent.service" Oct 2 19:36:26.485531 ignition[1354]: INFO : files: op(8): op(9): [started] writing unit "amazon-ssm-agent.service" at "/sysroot/etc/systemd/system/amazon-ssm-agent.service" Oct 2 19:36:26.485531 ignition[1354]: INFO : files: op(8): op(9): [finished] writing unit "amazon-ssm-agent.service" at "/sysroot/etc/systemd/system/amazon-ssm-agent.service" Oct 2 19:36:26.485531 ignition[1354]: INFO : files: op(8): [finished] processing unit "amazon-ssm-agent.service" Oct 2 19:36:26.485531 ignition[1354]: INFO : files: op(a): [started] processing unit "nvidia.service" Oct 2 19:36:26.485531 ignition[1354]: INFO : files: op(a): [finished] processing unit "nvidia.service" Oct 2 19:36:26.485531 ignition[1354]: INFO : files: op(b): [started] processing unit "etcd-member.service" Oct 2 19:36:26.485531 ignition[1354]: INFO : files: op(b): op(c): [started] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" Oct 2 19:36:26.485531 ignition[1354]: INFO : files: op(b): op(c): [finished] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" Oct 2 19:36:26.485531 ignition[1354]: INFO : files: op(b): [finished] processing unit "etcd-member.service" Oct 2 19:36:26.547025 ignition[1354]: INFO : files: op(d): [started] setting preset to enabled for "coreos-metadata-sshkeys@.service " Oct 2 19:36:26.547025 ignition[1354]: INFO : files: op(d): [finished] setting preset to enabled for "coreos-metadata-sshkeys@.service " Oct 2 19:36:26.547025 ignition[1354]: INFO : files: op(e): [started] setting preset to enabled for "amazon-ssm-agent.service" Oct 2 19:36:26.547025 ignition[1354]: INFO : files: op(e): [finished] setting preset to enabled for "amazon-ssm-agent.service" Oct 2 19:36:26.547025 ignition[1354]: INFO : files: op(f): [started] setting preset to enabled for "nvidia.service" Oct 2 19:36:26.547025 ignition[1354]: INFO : files: op(f): [finished] setting preset to enabled for "nvidia.service" Oct 2 19:36:26.547025 ignition[1354]: INFO : files: op(10): [started] setting preset to enabled for "etcd-member.service" Oct 2 19:36:26.547025 ignition[1354]: INFO : files: op(10): [finished] setting preset to enabled for "etcd-member.service" Oct 2 19:36:26.575109 ignition[1354]: INFO : files: createResultFile: createFiles: op(11): [started] writing file "/sysroot/etc/.ignition-result.json" Oct 2 19:36:26.575109 ignition[1354]: INFO : files: createResultFile: createFiles: op(11): [finished] writing file "/sysroot/etc/.ignition-result.json" Oct 2 19:36:26.575109 ignition[1354]: INFO : files: files passed Oct 2 19:36:26.575109 ignition[1354]: INFO : Ignition finished successfully Oct 2 19:36:26.586654 systemd[1]: Finished ignition-files.service. Oct 2 19:36:26.589000 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' Oct 2 19:36:26.600739 systemd[1]: Starting initrd-setup-root-after-ignition.service... Oct 2 19:36:26.616892 systemd[1]: torcx-profile-populate.service was skipped because of an unmet condition check (ConditionPathExists=/sysroot/etc/torcx/next-profile). Oct 2 19:36:26.621307 systemd[1]: Starting ignition-quench.service... Oct 2 19:36:26.636000 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' Oct 2 19:36:26.636000 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' Oct 2 19:36:26.634930 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 2 19:36:26.635208 systemd[1]: Finished ignition-quench.service. Oct 2 19:36:26.679343 initrd-setup-root-after-ignition[1379]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 2 19:36:26.684492 systemd[1]: Finished initrd-setup-root-after-ignition.service. Oct 2 19:36:26.685000 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' Oct 2 19:36:26.686920 systemd[1]: Reached target ignition-complete.target. Oct 2 19:36:26.703942 systemd[1]: Starting initrd-parse-etc.service... Oct 2 19:36:26.753487 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 2 19:36:26.754822 systemd[1]: Finished initrd-parse-etc.service. Oct 2 19:36:26.756000 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' Oct 2 19:36:26.756000 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' Oct 2 19:36:26.758286 systemd[1]: Reached target initrd-fs.target. Oct 2 19:36:26.760530 systemd[1]: Reached target initrd.target. Oct 2 19:36:26.763532 systemd[1]: dracut-mount.service was skipped because no trigger condition checks were met. Oct 2 19:36:26.764986 systemd[1]: Starting dracut-pre-pivot.service... Oct 2 19:36:26.809494 systemd[1]: Finished dracut-pre-pivot.service. Oct 2 19:36:26.815234 kernel: kauditd_printk_skb: 15 callbacks suppressed Oct 2 19:36:26.815279 kernel: audit: type=1130 audit(1696275386.808:41): 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' Oct 2 19:36:26.808000 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' Oct 2 19:36:26.815594 systemd[1]: Starting initrd-cleanup.service... Oct 2 19:36:26.842021 systemd[1]: Stopped target nss-lookup.target. Oct 2 19:36:26.845606 systemd[1]: Stopped target remote-cryptsetup.target. Oct 2 19:36:26.849394 systemd[1]: Stopped target timers.target. Oct 2 19:36:26.852637 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 2 19:36:26.854866 systemd[1]: Stopped dracut-pre-pivot.service. Oct 2 19:36:26.857000 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' Oct 2 19:36:26.858668 systemd[1]: Stopped target initrd.target. Oct 2 19:36:26.867205 kernel: audit: type=1131 audit(1696275386.857:42): 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' Oct 2 19:36:26.869007 systemd[1]: Stopped target basic.target. Oct 2 19:36:26.872241 systemd[1]: Stopped target ignition-complete.target. Oct 2 19:36:26.876079 systemd[1]: Stopped target ignition-diskful.target. Oct 2 19:36:26.879762 systemd[1]: Stopped target initrd-root-device.target. Oct 2 19:36:26.883629 systemd[1]: Stopped target remote-fs.target. Oct 2 19:36:26.887170 systemd[1]: Stopped target remote-fs-pre.target. Oct 2 19:36:26.890803 systemd[1]: Stopped target sysinit.target. Oct 2 19:36:26.894162 systemd[1]: Stopped target local-fs.target. Oct 2 19:36:26.897541 systemd[1]: Stopped target local-fs-pre.target. Oct 2 19:36:26.901059 systemd[1]: Stopped target swap.target. Oct 2 19:36:26.904147 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 2 19:36:26.906406 systemd[1]: Stopped dracut-pre-mount.service. Oct 2 19:36:26.908000 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' Oct 2 19:36:26.909855 systemd[1]: Stopped target cryptsetup.target. Oct 2 19:36:26.920288 kernel: audit: type=1131 audit(1696275386.908:43): 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' Oct 2 19:36:26.920284 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 2 19:36:26.922447 systemd[1]: Stopped dracut-initqueue.service. Oct 2 19:36:26.924000 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' Oct 2 19:36:26.925796 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Oct 2 19:36:26.926063 systemd[1]: Stopped initrd-setup-root-after-ignition.service. Oct 2 19:36:26.935241 kernel: audit: type=1131 audit(1696275386.924:44): 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' Oct 2 19:36:26.938000 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' Oct 2 19:36:26.939205 systemd[1]: ignition-files.service: Deactivated successfully. Oct 2 19:36:26.949993 kernel: audit: type=1131 audit(1696275386.938:45): 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' Oct 2 19:36:26.939422 systemd[1]: Stopped ignition-files.service. Oct 2 19:36:26.952000 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' Oct 2 19:36:26.955159 systemd[1]: Stopping ignition-mount.service... Oct 2 19:36:26.972781 kernel: audit: type=1131 audit(1696275386.952:46): 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' Oct 2 19:36:26.963982 systemd[1]: Stopping iscsiuio.service... Oct 2 19:36:26.971873 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 2 19:36:26.975393 systemd[1]: Stopped kmod-static-nodes.service. Oct 2 19:36:26.979000 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' Oct 2 19:36:26.989720 systemd[1]: Stopping sysroot-boot.service... Oct 2 19:36:26.994187 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 2 19:36:26.998536 systemd[1]: Stopped systemd-udev-trigger.service. Oct 2 19:36:27.000333 kernel: audit: type=1131 audit(1696275386.979:47): 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' Oct 2 19:36:27.002352 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 2 19:36:27.002573 systemd[1]: Stopped dracut-pre-trigger.service. Oct 2 19:36:27.001000 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' Oct 2 19:36:27.010987 systemd[1]: iscsiuio.service: Deactivated successfully. Oct 2 19:36:27.011238 systemd[1]: Stopped iscsiuio.service. Oct 2 19:36:27.027231 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 2 19:36:27.027461 systemd[1]: Finished initrd-cleanup.service. Oct 2 19:36:27.033948 ignition[1392]: INFO : Ignition 2.14.0 Oct 2 19:36:27.033948 ignition[1392]: INFO : Stage: umount Oct 2 19:36:27.047914 kernel: audit: type=1131 audit(1696275387.001:48): 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' Oct 2 19:36:27.047954 kernel: audit: type=1131 audit(1696275387.003:49): 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' Oct 2 19:36:27.003000 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' Oct 2 19:36:27.048084 ignition[1392]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign" Oct 2 19:36:27.048084 ignition[1392]: DEBUG : parsing config with SHA512: 6629d8e825d60c9c9d4629d8547ef9a0b839d6b01b7f61a481a1f23308c924b8b0bbf10cae7f7fe3bcaf88b23d1a81baa7771c3670728d4d2a1e665216a1de7b Oct 2 19:36:27.019000 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' Oct 2 19:36:27.069084 kernel: audit: type=1131 audit(1696275387.019:50): 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' Oct 2 19:36:27.028000 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' Oct 2 19:36:27.028000 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' Oct 2 19:36:27.078105 ignition[1392]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Oct 2 19:36:27.081021 ignition[1392]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Oct 2 19:36:27.091341 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 2 19:36:27.092000 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' Oct 2 19:36:27.091520 systemd[1]: Stopped sysroot-boot.service. Oct 2 19:36:27.098447 ignition[1392]: INFO : PUT result: OK Oct 2 19:36:27.102305 ignition[1392]: INFO : umount: umount passed Oct 2 19:36:27.104200 ignition[1392]: INFO : Ignition finished successfully Oct 2 19:36:27.106906 systemd[1]: ignition-mount.service: Deactivated successfully. Oct 2 19:36:27.107117 systemd[1]: Stopped ignition-mount.service. Oct 2 19:36:27.109000 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' Oct 2 19:36:27.112135 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 2 19:36:27.112000 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' Oct 2 19:36:27.112229 systemd[1]: Stopped ignition-disks.service. Oct 2 19:36:27.117410 systemd[1]: ignition-kargs.service: Deactivated successfully. Oct 2 19:36:27.117503 systemd[1]: Stopped ignition-kargs.service. Oct 2 19:36:27.123000 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' Oct 2 19:36:27.126325 systemd[1]: ignition-fetch.service: Deactivated successfully. Oct 2 19:36:27.126404 systemd[1]: Stopped ignition-fetch.service. Oct 2 19:36:27.133000 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' Oct 2 19:36:27.134704 systemd[1]: Stopped target network.target. Oct 2 19:36:27.137711 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Oct 2 19:36:27.137810 systemd[1]: Stopped ignition-fetch-offline.service. Oct 2 19:36:27.143000 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' Oct 2 19:36:27.146639 systemd[1]: Stopped target paths.target. Oct 2 19:36:27.148201 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 2 19:36:27.153104 systemd[1]: Stopped systemd-ask-password-console.path. Oct 2 19:36:27.155279 systemd[1]: Stopped target slices.target. Oct 2 19:36:27.158518 systemd[1]: Stopped target sockets.target. Oct 2 19:36:27.161464 systemd[1]: iscsid.socket: Deactivated successfully. Oct 2 19:36:27.164637 systemd[1]: Closed iscsid.socket. Oct 2 19:36:27.167288 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 2 19:36:27.167376 systemd[1]: Closed iscsiuio.socket. Oct 2 19:36:27.170443 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 2 19:36:27.173697 systemd[1]: Stopped ignition-setup.service. Oct 2 19:36:27.172000 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' Oct 2 19:36:27.176808 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 2 19:36:27.176899 systemd[1]: Stopped initrd-setup-root.service. Oct 2 19:36:27.179000 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' Oct 2 19:36:27.182415 systemd[1]: Stopping systemd-networkd.service... Oct 2 19:36:27.185605 systemd[1]: Stopping systemd-resolved.service... Oct 2 19:36:27.190131 systemd-networkd[1199]: eth0: DHCPv6 lease lost Oct 2 19:36:27.193870 systemd[1]: sysroot-boot.mount: Deactivated successfully. Oct 2 19:36:27.194000 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' Oct 2 19:36:27.194772 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 2 19:36:27.194972 systemd[1]: Stopped systemd-networkd.service. Oct 2 19:36:27.204000 audit: BPF prog-id=9 op=UNLOAD Oct 2 19:36:27.197326 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 2 19:36:27.218000 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' Oct 2 19:36:27.197394 systemd[1]: Closed systemd-networkd.socket. Oct 2 19:36:27.201860 systemd[1]: Stopping network-cleanup.service... Oct 2 19:36:27.205089 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Oct 2 19:36:27.205222 systemd[1]: Stopped parse-ip-for-networkd.service. Oct 2 19:36:27.220107 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 2 19:36:27.221454 systemd[1]: Stopped systemd-sysctl.service. Oct 2 19:36:27.231000 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' Oct 2 19:36:27.232983 systemd[1]: systemd-modules-load.service: Deactivated successfully. Oct 2 19:36:27.233106 systemd[1]: Stopped systemd-modules-load.service. Oct 2 19:36:27.235000 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' Oct 2 19:36:27.244308 systemd[1]: Stopping systemd-udevd.service... Oct 2 19:36:27.250299 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Oct 2 19:36:27.253713 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 2 19:36:27.255829 systemd[1]: Stopped systemd-resolved.service. Oct 2 19:36:27.257000 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' Oct 2 19:36:27.260000 audit: BPF prog-id=6 op=UNLOAD Oct 2 19:36:27.263530 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 2 19:36:27.264179 systemd[1]: Stopped systemd-udevd.service. Oct 2 19:36:27.274000 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' Oct 2 19:36:27.278348 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 2 19:36:27.279508 systemd[1]: Stopped network-cleanup.service. Oct 2 19:36:27.279000 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' Oct 2 19:36:27.282403 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 2 19:36:27.282480 systemd[1]: Closed systemd-udevd-control.socket. Oct 2 19:36:27.288000 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' Oct 2 19:36:27.284960 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 2 19:36:27.291000 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' Oct 2 19:36:27.298000 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' Oct 2 19:36:27.285033 systemd[1]: Closed systemd-udevd-kernel.socket. Oct 2 19:36:27.287642 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 2 19:36:27.287923 systemd[1]: Stopped dracut-pre-udev.service. Oct 2 19:36:27.291263 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 2 19:36:27.291344 systemd[1]: Stopped dracut-cmdline.service. Oct 2 19:36:27.293377 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 2 19:36:27.293451 systemd[1]: Stopped dracut-cmdline-ask.service. Oct 2 19:36:27.302646 systemd[1]: Starting initrd-udevadm-cleanup-db.service... Oct 2 19:36:27.318009 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 2 19:36:27.318000 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' Oct 2 19:36:27.318143 systemd[1]: Stopped systemd-vconsole-setup.service. Oct 2 19:36:27.343757 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 2 19:36:27.346034 systemd[1]: Finished initrd-udevadm-cleanup-db.service. Oct 2 19:36:27.347000 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' Oct 2 19:36:27.347000 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' Oct 2 19:36:27.350486 systemd[1]: Reached target initrd-switch-root.target. Oct 2 19:36:27.359290 systemd[1]: Starting initrd-switch-root.service... Oct 2 19:36:27.383724 systemd[1]: Switching root. Oct 2 19:36:27.411948 iscsid[1204]: iscsid shutting down. Oct 2 19:36:27.414018 systemd-journald[308]: Received SIGTERM from PID 1 (n/a). Oct 2 19:36:27.414121 systemd-journald[308]: Journal stopped Oct 2 19:36:39.423920 kernel: SELinux: Class mctp_socket not defined in policy. Oct 2 19:36:39.424526 kernel: SELinux: Class anon_inode not defined in policy. Oct 2 19:36:39.424562 kernel: SELinux: the above unknown classes and permissions will be allowed Oct 2 19:36:39.424594 kernel: SELinux: policy capability network_peer_controls=1 Oct 2 19:36:39.424625 kernel: SELinux: policy capability open_perms=1 Oct 2 19:36:39.424661 kernel: SELinux: policy capability extended_socket_class=1 Oct 2 19:36:39.424692 kernel: SELinux: policy capability always_check_network=0 Oct 2 19:36:39.424723 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 2 19:36:39.424817 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 2 19:36:39.424854 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 2 19:36:39.424885 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Oct 2 19:36:39.424917 systemd[1]: Successfully loaded SELinux policy in 135.639ms. Oct 2 19:36:39.425201 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 19.776ms. Oct 2 19:36:39.425239 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) Oct 2 19:36:39.425272 systemd[1]: Detected virtualization amazon. Oct 2 19:36:39.425302 systemd[1]: Detected architecture arm64. Oct 2 19:36:39.425337 systemd[1]: Detected first boot. Oct 2 19:36:39.425370 systemd[1]: Initializing machine ID from VM UUID. Oct 2 19:36:39.425400 systemd[1]: Populated /etc with preset unit settings. Oct 2 19:36:39.425433 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Oct 2 19:36:39.425467 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Oct 2 19:36:39.425501 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. Oct 2 19:36:39.425595 kernel: kauditd_printk_skb: 32 callbacks suppressed Oct 2 19:36:39.425629 kernel: audit: type=1334 audit(1696275398.819:83): prog-id=12 op=LOAD Oct 2 19:36:39.425662 kernel: audit: type=1334 audit(1696275398.819:84): prog-id=3 op=UNLOAD Oct 2 19:36:39.425691 kernel: audit: type=1334 audit(1696275398.821:85): prog-id=13 op=LOAD Oct 2 19:36:39.425724 kernel: audit: type=1334 audit(1696275398.823:86): prog-id=14 op=LOAD Oct 2 19:36:39.425755 kernel: audit: type=1334 audit(1696275398.823:87): prog-id=4 op=UNLOAD Oct 2 19:36:39.425784 kernel: audit: type=1334 audit(1696275398.823:88): prog-id=5 op=UNLOAD Oct 2 19:36:39.425833 kernel: audit: type=1334 audit(1696275398.828:89): prog-id=15 op=LOAD Oct 2 19:36:39.425864 kernel: audit: type=1334 audit(1696275398.828:90): prog-id=12 op=UNLOAD Oct 2 19:36:39.425895 kernel: audit: type=1334 audit(1696275398.831:91): prog-id=16 op=LOAD Oct 2 19:36:39.425927 systemd[1]: iscsid.service: Deactivated successfully. Oct 2 19:36:39.425962 kernel: audit: type=1334 audit(1696275398.833:92): prog-id=17 op=LOAD Oct 2 19:36:39.425994 systemd[1]: Stopped iscsid.service. Oct 2 19:36:39.426026 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 2 19:36:39.426074 systemd[1]: Stopped initrd-switch-root.service. Oct 2 19:36:39.426109 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 2 19:36:39.426141 systemd[1]: Created slice system-addon\x2dconfig.slice. Oct 2 19:36:39.426171 systemd[1]: Created slice system-addon\x2drun.slice. Oct 2 19:36:39.426202 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice. Oct 2 19:36:39.426236 systemd[1]: Created slice system-getty.slice. Oct 2 19:36:39.426270 systemd[1]: Created slice system-modprobe.slice. Oct 2 19:36:39.426301 systemd[1]: Created slice system-serial\x2dgetty.slice. Oct 2 19:36:39.426332 systemd[1]: Created slice system-system\x2dcloudinit.slice. Oct 2 19:36:39.426364 systemd[1]: Created slice system-systemd\x2dfsck.slice. Oct 2 19:36:39.426397 systemd[1]: Created slice user.slice. Oct 2 19:36:39.429144 systemd[1]: Started systemd-ask-password-console.path. Oct 2 19:36:39.429201 systemd[1]: Started systemd-ask-password-wall.path. Oct 2 19:36:39.429242 systemd[1]: Set up automount boot.automount. Oct 2 19:36:39.429276 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount. Oct 2 19:36:39.429310 systemd[1]: Stopped target initrd-switch-root.target. Oct 2 19:36:39.429345 systemd[1]: Stopped target initrd-fs.target. Oct 2 19:36:39.429376 systemd[1]: Stopped target initrd-root-fs.target. Oct 2 19:36:39.429407 systemd[1]: Reached target integritysetup.target. Oct 2 19:36:39.429437 systemd[1]: Reached target remote-cryptsetup.target. Oct 2 19:36:39.429469 systemd[1]: Reached target remote-fs.target. Oct 2 19:36:39.429498 systemd[1]: Reached target slices.target. Oct 2 19:36:39.429528 systemd[1]: Reached target swap.target. Oct 2 19:36:39.429562 systemd[1]: Reached target torcx.target. Oct 2 19:36:39.429595 systemd[1]: Reached target veritysetup.target. Oct 2 19:36:39.429624 systemd[1]: Listening on systemd-coredump.socket. Oct 2 19:36:39.429654 systemd[1]: Listening on systemd-initctl.socket. Oct 2 19:36:39.429753 systemd[1]: Listening on systemd-networkd.socket. Oct 2 19:36:39.429788 systemd[1]: Listening on systemd-udevd-control.socket. Oct 2 19:36:39.429838 systemd[1]: Listening on systemd-udevd-kernel.socket. Oct 2 19:36:39.429870 systemd[1]: Listening on systemd-userdbd.socket. Oct 2 19:36:39.429903 systemd[1]: Mounting dev-hugepages.mount... Oct 2 19:36:39.429939 systemd[1]: Mounting dev-mqueue.mount... Oct 2 19:36:39.429972 systemd[1]: Mounting media.mount... Oct 2 19:36:39.430002 systemd[1]: Mounting sys-kernel-debug.mount... Oct 2 19:36:39.430034 systemd[1]: Mounting sys-kernel-tracing.mount... Oct 2 19:36:39.430086 systemd[1]: Mounting tmp.mount... Oct 2 19:36:39.430119 systemd[1]: Starting flatcar-tmpfiles.service... Oct 2 19:36:39.430150 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Oct 2 19:36:39.430180 systemd[1]: Starting kmod-static-nodes.service... Oct 2 19:36:39.430210 systemd[1]: Starting modprobe@configfs.service... Oct 2 19:36:39.430245 systemd[1]: Starting modprobe@dm_mod.service... Oct 2 19:36:39.430277 systemd[1]: Starting modprobe@drm.service... Oct 2 19:36:39.430309 systemd[1]: Starting modprobe@efi_pstore.service... Oct 2 19:36:39.430339 systemd[1]: Starting modprobe@fuse.service... Oct 2 19:36:39.430368 systemd[1]: Starting modprobe@loop.service... Oct 2 19:36:39.430399 systemd[1]: setup-nsswitch.service was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Oct 2 19:36:39.430430 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 2 19:36:39.430460 systemd[1]: Stopped systemd-fsck-root.service. Oct 2 19:36:39.430491 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 2 19:36:39.430526 systemd[1]: Stopped systemd-fsck-usr.service. Oct 2 19:36:39.430558 systemd[1]: Stopped systemd-journald.service. Oct 2 19:36:39.430588 systemd[1]: Starting systemd-journald.service... Oct 2 19:36:39.430620 systemd[1]: Starting systemd-modules-load.service... Oct 2 19:36:39.430652 systemd[1]: Starting systemd-network-generator.service... Oct 2 19:36:39.430682 systemd[1]: Starting systemd-remount-fs.service... Oct 2 19:36:39.430712 systemd[1]: Starting systemd-udev-trigger.service... Oct 2 19:36:39.430748 systemd[1]: verity-setup.service: Deactivated successfully. Oct 2 19:36:39.430778 systemd[1]: Stopped verity-setup.service. Oct 2 19:36:39.430812 systemd[1]: Mounted dev-hugepages.mount. Oct 2 19:36:39.430841 systemd[1]: Mounted dev-mqueue.mount. Oct 2 19:36:39.430871 systemd[1]: Mounted sys-kernel-debug.mount. Oct 2 19:36:39.430900 systemd[1]: Mounted sys-kernel-tracing.mount. Oct 2 19:36:39.430929 systemd[1]: Mounted media.mount. Oct 2 19:36:39.430961 systemd[1]: Mounted tmp.mount. Oct 2 19:36:39.430991 systemd[1]: Finished kmod-static-nodes.service. Oct 2 19:36:39.431023 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 2 19:36:39.431093 systemd[1]: Finished modprobe@dm_mod.service. Oct 2 19:36:39.431132 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 2 19:36:39.431162 systemd[1]: Finished modprobe@drm.service. Oct 2 19:36:39.431192 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 2 19:36:39.431224 systemd[1]: Finished modprobe@efi_pstore.service. Oct 2 19:36:39.431254 systemd[1]: Finished systemd-network-generator.service. Oct 2 19:36:39.431291 systemd[1]: Finished systemd-remount-fs.service. Oct 2 19:36:39.431324 systemd[1]: Reached target network-pre.target. Oct 2 19:36:39.431358 systemd[1]: remount-root.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Oct 2 19:36:39.431388 systemd[1]: Starting systemd-hwdb-update.service... Oct 2 19:36:39.431419 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 2 19:36:39.431450 systemd[1]: Starting systemd-random-seed.service... Oct 2 19:36:39.431482 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 2 19:36:39.431511 kernel: loop: module loaded Oct 2 19:36:39.431541 systemd[1]: Finished modprobe@configfs.service. Oct 2 19:36:39.431577 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 2 19:36:39.431607 systemd[1]: Finished modprobe@loop.service. Oct 2 19:36:39.431639 systemd[1]: Mounting sys-kernel-config.mount... Oct 2 19:36:39.431669 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Oct 2 19:36:39.431698 systemd[1]: Mounted sys-kernel-config.mount. Oct 2 19:36:39.431732 systemd[1]: Finished systemd-modules-load.service. Oct 2 19:36:39.431763 systemd[1]: Starting systemd-sysctl.service... Oct 2 19:36:39.431794 kernel: fuse: init (API version 7.34) Oct 2 19:36:39.431823 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 2 19:36:39.431855 systemd[1]: Finished modprobe@fuse.service. Oct 2 19:36:39.431885 systemd[1]: Mounting sys-fs-fuse-connections.mount... Oct 2 19:36:39.431914 systemd[1]: Mounted sys-fs-fuse-connections.mount. Oct 2 19:36:39.431944 systemd[1]: Finished systemd-random-seed.service. Oct 2 19:36:39.431977 systemd[1]: Reached target first-boot-complete.target. Oct 2 19:36:39.432015 systemd-journald[1499]: Journal started Oct 2 19:36:39.432136 systemd-journald[1499]: Runtime Journal (/run/log/journal/ec2fc7b1f3c281e1983e7d6efb389207) is 8.0M, max 75.4M, 67.4M free. Oct 2 19:36:30.328000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 2 19:36:30.959000 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 Oct 2 19:36:39.435696 systemd[1]: Started systemd-journald.service. Oct 2 19:36:30.960000 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 Oct 2 19:36:30.960000 audit: BPF prog-id=10 op=LOAD Oct 2 19:36:30.960000 audit: BPF prog-id=10 op=UNLOAD Oct 2 19:36:30.960000 audit: BPF prog-id=11 op=LOAD Oct 2 19:36:30.960000 audit: BPF prog-id=11 op=UNLOAD Oct 2 19:36:38.819000 audit: BPF prog-id=12 op=LOAD Oct 2 19:36:38.819000 audit: BPF prog-id=3 op=UNLOAD Oct 2 19:36:38.821000 audit: BPF prog-id=13 op=LOAD Oct 2 19:36:38.823000 audit: BPF prog-id=14 op=LOAD Oct 2 19:36:38.823000 audit: BPF prog-id=4 op=UNLOAD Oct 2 19:36:38.823000 audit: BPF prog-id=5 op=UNLOAD Oct 2 19:36:38.828000 audit: BPF prog-id=15 op=LOAD Oct 2 19:36:38.828000 audit: BPF prog-id=12 op=UNLOAD Oct 2 19:36:38.831000 audit: BPF prog-id=16 op=LOAD Oct 2 19:36:38.833000 audit: BPF prog-id=17 op=LOAD Oct 2 19:36:38.833000 audit: BPF prog-id=13 op=UNLOAD Oct 2 19:36:38.833000 audit: BPF prog-id=14 op=UNLOAD Oct 2 19:36:38.836000 audit: BPF prog-id=18 op=LOAD Oct 2 19:36:38.836000 audit: BPF prog-id=15 op=UNLOAD Oct 2 19:36:38.838000 audit: BPF prog-id=19 op=LOAD Oct 2 19:36:38.841000 audit: BPF prog-id=20 op=LOAD Oct 2 19:36:38.841000 audit: BPF prog-id=16 op=UNLOAD Oct 2 19:36:38.841000 audit: BPF prog-id=17 op=UNLOAD Oct 2 19:36:38.842000 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' Oct 2 19:36:38.852000 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' Oct 2 19:36:38.855000 audit: BPF prog-id=18 op=UNLOAD Oct 2 19:36:38.860000 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' Oct 2 19:36:38.860000 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' Oct 2 19:36:39.102000 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' Oct 2 19:36:39.110000 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' Oct 2 19:36:39.120000 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' Oct 2 19:36:39.120000 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' Oct 2 19:36:39.122000 audit: BPF prog-id=21 op=LOAD Oct 2 19:36:39.122000 audit: BPF prog-id=22 op=LOAD Oct 2 19:36:39.122000 audit: BPF prog-id=23 op=LOAD Oct 2 19:36:39.179000 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' Oct 2 19:36:39.217000 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' Oct 2 19:36:39.228000 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' Oct 2 19:36:39.228000 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' Oct 2 19:36:39.232000 audit: BPF prog-id=20 op=UNLOAD Oct 2 19:36:39.236000 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' Oct 2 19:36:39.236000 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' Oct 2 19:36:39.236000 audit: BPF prog-id=19 op=UNLOAD Oct 2 19:36:39.246000 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' Oct 2 19:36:39.246000 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' Oct 2 19:36:39.253000 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' Oct 2 19:36:39.257000 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' Oct 2 19:36:39.304000 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' Oct 2 19:36:39.304000 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' Oct 2 19:36:39.313000 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' Oct 2 19:36:39.313000 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' Oct 2 19:36:39.363000 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' Oct 2 19:36:39.391000 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' Oct 2 19:36:39.391000 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' Oct 2 19:36:39.418000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Oct 2 19:36:39.418000 audit[1499]: SYSCALL arch=c00000b7 syscall=211 success=yes exit=60 a0=6 a1=ffffd3c16040 a2=4000 a3=1 items=0 ppid=1 pid=1499 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) Oct 2 19:36:39.418000 audit: PROCTITLE proctitle="/usr/lib/systemd/systemd-journald" Oct 2 19:36:39.422000 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' Oct 2 19:36:39.435000 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' Oct 2 19:36:38.817569 systemd[1]: Queued start job for default target multi-user.target. Oct 2 19:36:31.683696 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2023-10-02T19:36:31Z" 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.0 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.0 /var/lib/torcx/store]" Oct 2 19:36:38.843268 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 2 19:36:31.707657 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2023-10-02T19:36:31Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 2 19:36:39.438460 systemd[1]: Starting systemd-journal-flush.service... Oct 2 19:36:31.707709 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2023-10-02T19:36:31Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 2 19:36:31.707774 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2023-10-02T19:36:31Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Oct 2 19:36:31.707802 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2023-10-02T19:36:31Z" level=debug msg="skipped missing lower profile" missing profile=oem Oct 2 19:36:31.707872 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2023-10-02T19:36:31Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Oct 2 19:36:31.707902 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2023-10-02T19:36:31Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Oct 2 19:36:31.708336 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2023-10-02T19:36:31Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Oct 2 19:36:31.708409 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2023-10-02T19:36:31Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 2 19:36:31.708443 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2023-10-02T19:36:31Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 2 19:36:31.709302 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2023-10-02T19:36:31Z" 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 Oct 2 19:36:31.709381 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2023-10-02T19:36:31Z" 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 Oct 2 19:36:31.709427 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2023-10-02T19:36:31Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3510.3.0: no such file or directory" path=/usr/share/oem/torcx/store/3510.3.0 Oct 2 19:36:31.709466 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2023-10-02T19:36:31Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Oct 2 19:36:31.709510 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2023-10-02T19:36:31Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3510.3.0: no such file or directory" path=/var/lib/torcx/store/3510.3.0 Oct 2 19:36:31.709548 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2023-10-02T19:36:31Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Oct 2 19:36:37.752221 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2023-10-02T19:36:37Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 2 19:36:37.752735 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2023-10-02T19:36:37Z" 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 Oct 2 19:36:37.752989 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2023-10-02T19:36:37Z" 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 Oct 2 19:36:37.753441 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2023-10-02T19:36:37Z" 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 Oct 2 19:36:37.753544 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2023-10-02T19:36:37Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Oct 2 19:36:37.753679 /usr/lib/systemd/system-generators/torcx-generator[1425]: time="2023-10-02T19:36:37Z" 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 Oct 2 19:36:39.494454 systemd[1]: Finished systemd-sysctl.service. Oct 2 19:36:39.495000 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' Oct 2 19:36:39.583003 systemd-journald[1499]: Time spent on flushing to /var/log/journal/ec2fc7b1f3c281e1983e7d6efb389207 is 52.298ms for 1116 entries. Oct 2 19:36:39.583003 systemd-journald[1499]: System Journal (/var/log/journal/ec2fc7b1f3c281e1983e7d6efb389207) is 8.0M, max 195.6M, 187.6M free. Oct 2 19:36:39.723511 systemd-journald[1499]: Received client request to flush runtime journal. Oct 2 19:36:39.591000 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' Oct 2 19:36:39.603000 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' Oct 2 19:36:39.591132 systemd[1]: Finished systemd-udev-trigger.service. Oct 2 19:36:39.724281 udevadm[1540]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Oct 2 19:36:39.595260 systemd[1]: Starting systemd-udev-settle.service... Oct 2 19:36:39.602434 systemd[1]: Finished flatcar-tmpfiles.service. Oct 2 19:36:39.606789 systemd[1]: Starting systemd-sysusers.service... Oct 2 19:36:39.726089 systemd[1]: Finished systemd-journal-flush.service. Oct 2 19:36:39.726000 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' Oct 2 19:36:39.814440 systemd[1]: Finished systemd-sysusers.service. Oct 2 19:36:39.815000 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' Oct 2 19:36:40.465363 systemd[1]: Finished systemd-hwdb-update.service. Oct 2 19:36:40.468000 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' Oct 2 19:36:40.469000 audit: BPF prog-id=24 op=LOAD Oct 2 19:36:40.469000 audit: BPF prog-id=25 op=LOAD Oct 2 19:36:40.469000 audit: BPF prog-id=7 op=UNLOAD Oct 2 19:36:40.469000 audit: BPF prog-id=8 op=UNLOAD Oct 2 19:36:40.471806 systemd[1]: Starting systemd-udevd.service... Oct 2 19:36:40.519760 systemd-udevd[1544]: Using default interface naming scheme 'v252'. Oct 2 19:36:40.610448 systemd[1]: Started systemd-udevd.service. Oct 2 19:36:40.611000 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' Oct 2 19:36:40.613000 audit: BPF prog-id=26 op=LOAD Oct 2 19:36:40.619625 systemd[1]: Starting systemd-networkd.service... Oct 2 19:36:40.627000 audit: BPF prog-id=27 op=LOAD Oct 2 19:36:40.628000 audit: BPF prog-id=28 op=LOAD Oct 2 19:36:40.628000 audit: BPF prog-id=29 op=LOAD Oct 2 19:36:40.630532 systemd[1]: Starting systemd-userdbd.service... Oct 2 19:36:40.747136 systemd[1]: Started systemd-userdbd.service. Oct 2 19:36:40.747000 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' Oct 2 19:36:40.772730 systemd[1]: Condition check resulted in dev-ttyS0.device being skipped. Oct 2 19:36:40.784808 (udev-worker)[1560]: Network interface NamePolicy= disabled on kernel command line. Oct 2 19:36:40.908864 systemd-networkd[1554]: lo: Link UP Oct 2 19:36:40.908888 systemd-networkd[1554]: lo: Gained carrier Oct 2 19:36:40.909894 systemd-networkd[1554]: Enumeration completed Oct 2 19:36:40.910099 systemd[1]: Started systemd-networkd.service. Oct 2 19:36:40.910189 systemd-networkd[1554]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 2 19:36:40.910000 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' Oct 2 19:36:40.914304 systemd[1]: Starting systemd-networkd-wait-online.service... Oct 2 19:36:40.923118 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Oct 2 19:36:40.923869 systemd-networkd[1554]: eth0: Link UP Oct 2 19:36:40.924174 systemd-networkd[1554]: eth0: Gained carrier Oct 2 19:36:40.936289 systemd-networkd[1554]: eth0: DHCPv4 address 172.31.17.106/20, gateway 172.31.16.1 acquired from 172.31.16.1 Oct 2 19:36:41.285085 kernel: BTRFS info: devid 1 device path /dev/disk/by-label/OEM changed to /dev/nvme0n1p6 scanned by (udev-worker) (1561) Oct 2 19:36:41.494008 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Oct 2 19:36:41.497082 systemd[1]: Finished systemd-udev-settle.service. Oct 2 19:36:41.498000 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' Oct 2 19:36:41.501729 systemd[1]: Starting lvm2-activation-early.service... Oct 2 19:36:41.588393 lvm[1663]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 2 19:36:41.622981 systemd[1]: Finished lvm2-activation-early.service. Oct 2 19:36:41.623000 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' Oct 2 19:36:41.625353 systemd[1]: Reached target cryptsetup.target. Oct 2 19:36:41.629472 systemd[1]: Starting lvm2-activation.service... Oct 2 19:36:41.644137 lvm[1664]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 2 19:36:41.681106 systemd[1]: Finished lvm2-activation.service. Oct 2 19:36:41.681000 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' Oct 2 19:36:41.683150 systemd[1]: Reached target local-fs-pre.target. Oct 2 19:36:41.684978 systemd[1]: var-lib-machines.mount was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Oct 2 19:36:41.685211 systemd[1]: Reached target local-fs.target. Oct 2 19:36:41.687148 systemd[1]: Reached target machines.target. Oct 2 19:36:41.702113 systemd[1]: Starting ldconfig.service... Oct 2 19:36:41.704401 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Oct 2 19:36:41.704697 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). Oct 2 19:36:41.707559 systemd[1]: Starting systemd-boot-update.service... Oct 2 19:36:41.712228 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service... Oct 2 19:36:41.716753 systemd[1]: Starting systemd-machine-id-commit.service... Oct 2 19:36:41.718808 systemd[1]: systemd-sysext.service was skipped because no trigger condition checks were met. Oct 2 19:36:41.718929 systemd[1]: ensure-sysext.service was skipped because no trigger condition checks were met. Oct 2 19:36:41.721253 systemd[1]: Starting systemd-tmpfiles-setup.service... Oct 2 19:36:41.770526 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 2 19:36:41.772568 systemd[1]: Finished systemd-machine-id-commit.service. Oct 2 19:36:41.773000 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' Oct 2 19:36:41.786660 systemd-tmpfiles[1669]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Oct 2 19:36:41.791579 systemd[1]: boot.automount: Got automount request for /boot, triggered by 1666 (bootctl) Oct 2 19:36:41.793804 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service... Oct 2 19:36:41.824540 systemd-tmpfiles[1669]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Oct 2 19:36:41.847726 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service. Oct 2 19:36:41.848000 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' Oct 2 19:36:41.852477 systemd-tmpfiles[1669]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Oct 2 19:36:42.051344 systemd-fsck[1674]: fsck.fat 4.2 (2021-01-31) Oct 2 19:36:42.051344 systemd-fsck[1674]: /dev/nvme0n1p1: 236 files, 113463/258078 clusters Oct 2 19:36:42.058650 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service. Oct 2 19:36:42.060000 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' Oct 2 19:36:42.063785 systemd[1]: Mounting boot.mount... Oct 2 19:36:42.092701 systemd[1]: Mounted boot.mount. Oct 2 19:36:42.118789 systemd[1]: Finished systemd-boot-update.service. Oct 2 19:36:42.119000 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' Oct 2 19:36:42.273539 systemd[1]: Finished systemd-tmpfiles-setup.service. Oct 2 19:36:42.274000 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' Oct 2 19:36:42.278021 systemd[1]: Starting audit-rules.service... Oct 2 19:36:42.282100 systemd[1]: Starting clean-ca-certificates.service... Oct 2 19:36:42.287847 systemd[1]: Starting systemd-journal-catalog-update.service... Oct 2 19:36:42.290000 audit: BPF prog-id=30 op=LOAD Oct 2 19:36:42.296915 systemd[1]: Starting systemd-resolved.service... Oct 2 19:36:42.299000 audit: BPF prog-id=31 op=LOAD Oct 2 19:36:42.305358 systemd[1]: Starting systemd-timesyncd.service... Oct 2 19:36:42.309264 systemd[1]: Starting systemd-update-utmp.service... Oct 2 19:36:42.348085 systemd[1]: Finished clean-ca-certificates.service. Oct 2 19:36:42.349000 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' Oct 2 19:36:42.350265 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Oct 2 19:36:42.357000 audit[1693]: SYSTEM_BOOT pid=1693 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg=' comm="systemd-update-utmp" exe="/usr/lib/systemd/systemd-update-utmp" hostname=? addr=? terminal=? res=success' Oct 2 19:36:42.364211 systemd-networkd[1554]: eth0: Gained IPv6LL Oct 2 19:36:42.369000 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' Oct 2 19:36:42.368526 systemd[1]: Finished systemd-update-utmp.service. Oct 2 19:36:42.373847 systemd[1]: Finished systemd-networkd-wait-online.service. Oct 2 19:36:42.375000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-networkd-wait-online comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:36:42.401740 systemd[1]: Finished systemd-journal-catalog-update.service. Oct 2 19:36:42.402000 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' Oct 2 19:36:42.489273 systemd[1]: Started systemd-timesyncd.service. Oct 2 19:36:42.490000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-timesyncd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:36:42.491365 systemd[1]: Reached target time-set.target. Oct 2 19:36:42.497817 systemd-resolved[1691]: Positive Trust Anchors: Oct 2 19:36:42.497847 systemd-resolved[1691]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 2 19:36:42.497899 systemd-resolved[1691]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Oct 2 19:36:42.110391 systemd-timesyncd[1692]: Contacted time server 216.218.254.202:123 (0.flatcar.pool.ntp.org). Oct 2 19:36:42.157280 systemd-journald[1499]: Time jumped backwards, rotating. Oct 2 19:36:42.132000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:36:42.111160 systemd-timesyncd[1692]: Initial clock synchronization to Mon 2023-10-02 19:36:42.110224 UTC. Oct 2 19:36:42.127086 systemd-resolved[1691]: Defaulting to hostname 'linux'. Oct 2 19:36:42.130701 systemd[1]: Started systemd-resolved.service. Oct 2 19:36:42.132792 systemd[1]: Reached target network.target. Oct 2 19:36:42.134500 systemd[1]: Reached target network-online.target. Oct 2 19:36:42.136420 systemd[1]: Reached target nss-lookup.target. Oct 2 19:36:42.191876 augenrules[1710]: No rules Oct 2 19:36:42.190000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Oct 2 19:36:42.190000 audit[1710]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffdb386780 a2=420 a3=0 items=0 ppid=1688 pid=1710 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) Oct 2 19:36:42.190000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573 Oct 2 19:36:42.194195 systemd[1]: Finished audit-rules.service. Oct 2 19:36:42.926463 ldconfig[1665]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 2 19:36:42.931981 systemd[1]: Finished ldconfig.service. Oct 2 19:36:42.936203 systemd[1]: Starting systemd-update-done.service... Oct 2 19:36:42.958439 systemd[1]: Finished systemd-update-done.service. Oct 2 19:36:42.960510 systemd[1]: Reached target sysinit.target. Oct 2 19:36:42.962379 systemd[1]: Started motdgen.path. Oct 2 19:36:42.963910 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path. Oct 2 19:36:42.966723 systemd[1]: Started logrotate.timer. Oct 2 19:36:42.969122 systemd[1]: Started mdadm.timer. Oct 2 19:36:42.970622 systemd[1]: Started systemd-tmpfiles-clean.timer. Oct 2 19:36:42.972496 systemd[1]: update-engine-stub.timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Oct 2 19:36:42.972567 systemd[1]: Reached target paths.target. Oct 2 19:36:42.974321 systemd[1]: Reached target timers.target. Oct 2 19:36:42.976440 systemd[1]: Listening on dbus.socket. Oct 2 19:36:42.980242 systemd[1]: Starting docker.socket... Oct 2 19:36:42.991437 systemd[1]: Listening on sshd.socket. Oct 2 19:36:42.993428 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). Oct 2 19:36:42.994315 systemd[1]: Listening on docker.socket. Oct 2 19:36:42.996106 systemd[1]: Reached target sockets.target. Oct 2 19:36:42.997829 systemd[1]: Reached target basic.target. Oct 2 19:36:42.999524 systemd[1]: addon-config@usr-share-oem.service was skipped because no trigger condition checks were met. Oct 2 19:36:42.999586 systemd[1]: addon-run@usr-share-oem.service was skipped because no trigger condition checks were met. Oct 2 19:36:43.002484 systemd[1]: Started amazon-ssm-agent.service. Oct 2 19:36:43.008271 systemd[1]: Starting containerd.service... Oct 2 19:36:43.017121 systemd[1]: Starting coreos-metadata-sshkeys@core.service... Oct 2 19:36:43.021439 systemd[1]: Starting coreos-metadata.service... Oct 2 19:36:43.025508 systemd[1]: Starting dbus.service... Oct 2 19:36:43.030117 systemd[1]: Starting enable-oem-cloudinit.service... Oct 2 19:36:43.036491 systemd[1]: Starting extend-filesystems.service... Oct 2 19:36:43.038207 systemd[1]: flatcar-setup-environment.service was skipped because of an unmet condition check (ConditionPathExists=/usr/share/oem/bin/flatcar-setup-environment). Oct 2 19:36:43.040608 systemd[1]: Starting motdgen.service... Oct 2 19:36:43.044362 systemd[1]: Started nvidia.service. Oct 2 19:36:43.048605 systemd[1]: Starting ssh-key-proc-cmdline.service... Oct 2 19:36:43.055888 systemd[1]: Starting sshd-keygen.service... Oct 2 19:36:43.062084 systemd[1]: Starting systemd-logind.service... Oct 2 19:36:43.064283 systemd[1]: systemd-pcrphase.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Oct 2 19:36:43.064392 systemd[1]: tcsd.service was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Oct 2 19:36:43.065321 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 2 19:36:43.066893 systemd[1]: Starting update-engine.service... Oct 2 19:36:43.078116 systemd[1]: Starting update-ssh-keys-after-ignition.service... Oct 2 19:36:43.124082 jq[1732]: true Oct 2 19:36:43.125095 jq[1724]: false Oct 2 19:36:43.138628 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Oct 2 19:36:43.139040 systemd[1]: Condition check resulted in enable-oem-cloudinit.service being skipped. Oct 2 19:36:43.151025 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 2 19:36:43.151406 systemd[1]: Finished ssh-key-proc-cmdline.service. Oct 2 19:36:43.183433 jq[1734]: true Oct 2 19:36:43.320489 dbus-daemon[1723]: [system] SELinux support is enabled Oct 2 19:36:43.320602 systemd[1]: motdgen.service: Deactivated successfully. Oct 2 19:36:43.320988 systemd[1]: Finished motdgen.service. Oct 2 19:36:43.323360 systemd[1]: Started dbus.service. Oct 2 19:36:43.336289 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). Oct 2 19:36:43.336335 systemd[1]: Reached target system-config.target. Oct 2 19:36:43.340148 systemd[1]: user-cloudinit-proc-cmdline.service was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Oct 2 19:36:43.340183 systemd[1]: Reached target user-config.target. Oct 2 19:36:43.362294 update_engine[1731]: I1002 19:36:43.361546 1731 main.cc:92] Flatcar Update Engine starting Oct 2 19:36:43.385684 dbus-daemon[1723]: [system] Activating systemd to hand-off: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.2' (uid=244 pid=1554 comm="/usr/lib/systemd/systemd-networkd" label="system_u:system_r:kernel_t:s0") Oct 2 19:36:43.390387 dbus-daemon[1723]: [system] Successfully activated service 'org.freedesktop.systemd1' Oct 2 19:36:43.398139 extend-filesystems[1725]: Found nvme0n1 Oct 2 19:36:43.398139 extend-filesystems[1725]: Found nvme0n1p1 Oct 2 19:36:43.398139 extend-filesystems[1725]: Found nvme0n1p2 Oct 2 19:36:43.398139 extend-filesystems[1725]: Found nvme0n1p3 Oct 2 19:36:43.398139 extend-filesystems[1725]: Found usr Oct 2 19:36:43.398139 extend-filesystems[1725]: Found nvme0n1p4 Oct 2 19:36:43.398139 extend-filesystems[1725]: Found nvme0n1p6 Oct 2 19:36:43.398139 extend-filesystems[1725]: Found nvme0n1p7 Oct 2 19:36:43.398139 extend-filesystems[1725]: Found nvme0n1p9 Oct 2 19:36:43.398139 extend-filesystems[1725]: Checking size of /dev/nvme0n1p9 Oct 2 19:36:43.396245 systemd[1]: Starting systemd-hostnamed.service... Oct 2 19:36:43.446745 update_engine[1731]: I1002 19:36:43.409335 1731 update_check_scheduler.cc:74] Next update check in 6m37s Oct 2 19:36:43.409081 systemd[1]: Started update-engine.service. Oct 2 19:36:43.520337 systemd[1]: Started locksmithd.service. Oct 2 19:36:43.586743 extend-filesystems[1725]: Resized partition /dev/nvme0n1p9 Oct 2 19:36:43.629009 extend-filesystems[1791]: resize2fs 1.46.5 (30-Dec-2021) Oct 2 19:36:43.661880 systemd-logind[1730]: Watching system buttons on /dev/input/event0 (Power Button) Oct 2 19:36:43.662656 bash[1792]: Updated "/home/core/.ssh/authorized_keys" Oct 2 19:36:43.664425 systemd[1]: Finished update-ssh-keys-after-ignition.service. Oct 2 19:36:43.671251 kernel: EXT4-fs (nvme0n1p9): resizing filesystem from 553472 to 1489915 blocks Oct 2 19:36:43.672676 systemd-logind[1730]: New seat seat0. Oct 2 19:36:43.675539 amazon-ssm-agent[1718]: 2023/10/02 19:36:43 Failed to load instance info from vault. RegistrationKey does not exist. Oct 2 19:36:43.687321 systemd[1]: Started systemd-logind.service. Oct 2 19:36:43.691892 systemd[1]: nvidia.service: Deactivated successfully. Oct 2 19:36:43.701569 amazon-ssm-agent[1718]: Initializing new seelog logger Oct 2 19:36:43.704496 amazon-ssm-agent[1718]: New Seelog Logger Creation Complete Oct 2 19:36:43.705096 amazon-ssm-agent[1718]: 2023/10/02 19:36:43 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Oct 2 19:36:43.705096 amazon-ssm-agent[1718]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Oct 2 19:36:43.710027 amazon-ssm-agent[1718]: 2023/10/02 19:36:43 processing appconfig overrides Oct 2 19:36:43.731969 kernel: EXT4-fs (nvme0n1p9): resized filesystem to 1489915 Oct 2 19:36:43.802146 extend-filesystems[1791]: Filesystem at /dev/nvme0n1p9 is mounted on /; on-line resizing required Oct 2 19:36:43.802146 extend-filesystems[1791]: old_desc_blocks = 1, new_desc_blocks = 1 Oct 2 19:36:43.802146 extend-filesystems[1791]: The filesystem on /dev/nvme0n1p9 is now 1489915 (4k) blocks long. Oct 2 19:36:43.825377 extend-filesystems[1725]: Resized filesystem in /dev/nvme0n1p9 Oct 2 19:36:43.828220 env[1739]: time="2023-10-02T19:36:43.813075004Z" level=info msg="starting containerd" revision=92b3a9d6f1b3bcc6dc74875cfdea653fe39f09c2 version=1.6.16 Oct 2 19:36:43.830807 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 2 19:36:43.831189 systemd[1]: Finished extend-filesystems.service. Oct 2 19:36:43.871901 coreos-metadata[1722]: Oct 02 19:36:43.871 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Oct 2 19:36:43.887324 coreos-metadata[1722]: Oct 02 19:36:43.887 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/instance-id: Attempt #1 Oct 2 19:36:43.888351 coreos-metadata[1722]: Oct 02 19:36:43.888 INFO Fetch successful Oct 2 19:36:43.888351 coreos-metadata[1722]: Oct 02 19:36:43.888 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/instance-type: Attempt #1 Oct 2 19:36:43.889202 coreos-metadata[1722]: Oct 02 19:36:43.889 INFO Fetch successful Oct 2 19:36:43.889202 coreos-metadata[1722]: Oct 02 19:36:43.889 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/local-ipv4: Attempt #1 Oct 2 19:36:43.890308 coreos-metadata[1722]: Oct 02 19:36:43.890 INFO Fetch successful Oct 2 19:36:43.890308 coreos-metadata[1722]: Oct 02 19:36:43.890 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/public-ipv4: Attempt #1 Oct 2 19:36:43.891097 coreos-metadata[1722]: Oct 02 19:36:43.890 INFO Fetch successful Oct 2 19:36:43.891097 coreos-metadata[1722]: Oct 02 19:36:43.891 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/placement/availability-zone: Attempt #1 Oct 2 19:36:43.892148 coreos-metadata[1722]: Oct 02 19:36:43.891 INFO Fetch successful Oct 2 19:36:43.892148 coreos-metadata[1722]: Oct 02 19:36:43.892 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/hostname: Attempt #1 Oct 2 19:36:43.893077 coreos-metadata[1722]: Oct 02 19:36:43.892 INFO Fetch successful Oct 2 19:36:43.893077 coreos-metadata[1722]: Oct 02 19:36:43.893 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/public-hostname: Attempt #1 Oct 2 19:36:43.894000 coreos-metadata[1722]: Oct 02 19:36:43.893 INFO Fetch successful Oct 2 19:36:43.894000 coreos-metadata[1722]: Oct 02 19:36:43.893 INFO Fetching http://169.254.169.254/2019-10-01/dynamic/instance-identity/document: Attempt #1 Oct 2 19:36:43.894676 coreos-metadata[1722]: Oct 02 19:36:43.894 INFO Fetch successful Oct 2 19:36:43.946815 systemd[1]: Finished coreos-metadata.service. Oct 2 19:36:43.951595 systemd[1]: Starting etcd-member.service... Oct 2 19:36:43.956684 dbus-daemon[1723]: [system] Successfully activated service 'org.freedesktop.hostname1' Oct 2 19:36:43.956994 systemd[1]: Started systemd-hostnamed.service. Oct 2 19:36:43.960377 dbus-daemon[1723]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.6' (uid=0 pid=1771 comm="/usr/lib/systemd/systemd-hostnamed" label="system_u:system_r:kernel_t:s0") Oct 2 19:36:43.965164 systemd[1]: Starting polkit.service... Oct 2 19:36:44.032940 env[1739]: time="2023-10-02T19:36:44.032840161Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Oct 2 19:36:44.041166 env[1739]: time="2023-10-02T19:36:44.040063501Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Oct 2 19:36:44.047423 env[1739]: time="2023-10-02T19:36:44.047342605Z" 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.132-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Oct 2 19:36:44.047423 env[1739]: time="2023-10-02T19:36:44.047412037Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Oct 2 19:36:44.047847 env[1739]: time="2023-10-02T19:36:44.047790637Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Oct 2 19:36:44.048053 env[1739]: time="2023-10-02T19:36:44.047841661Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Oct 2 19:36:44.048053 env[1739]: time="2023-10-02T19:36:44.047876977Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" Oct 2 19:36:44.048053 env[1739]: time="2023-10-02T19:36:44.047901817Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Oct 2 19:36:44.048212 env[1739]: time="2023-10-02T19:36:44.048115993Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Oct 2 19:36:44.048822 env[1739]: time="2023-10-02T19:36:44.048764629Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Oct 2 19:36:44.057609 polkitd[1816]: Started polkitd version 121 Oct 2 19:36:44.061373 env[1739]: time="2023-10-02T19:36:44.061245361Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Oct 2 19:36:44.061373 env[1739]: time="2023-10-02T19:36:44.061331593Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Oct 2 19:36:44.061554 env[1739]: time="2023-10-02T19:36:44.061502881Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" Oct 2 19:36:44.061554 env[1739]: time="2023-10-02T19:36:44.061534537Z" level=info msg="metadata content store policy set" policy=shared Oct 2 19:36:44.072139 env[1739]: time="2023-10-02T19:36:44.072070297Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Oct 2 19:36:44.072286 env[1739]: time="2023-10-02T19:36:44.072146101Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Oct 2 19:36:44.072286 env[1739]: time="2023-10-02T19:36:44.072181369Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Oct 2 19:36:44.072286 env[1739]: time="2023-10-02T19:36:44.072251725Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Oct 2 19:36:44.072456 env[1739]: time="2023-10-02T19:36:44.072289777Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Oct 2 19:36:44.072456 env[1739]: time="2023-10-02T19:36:44.072322561Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Oct 2 19:36:44.072456 env[1739]: time="2023-10-02T19:36:44.072353245Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Oct 2 19:36:44.073045 env[1739]: time="2023-10-02T19:36:44.072897313Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Oct 2 19:36:44.073045 env[1739]: time="2023-10-02T19:36:44.072967525Z" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1 Oct 2 19:36:44.073045 env[1739]: time="2023-10-02T19:36:44.073005865Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Oct 2 19:36:44.073045 env[1739]: time="2023-10-02T19:36:44.073036561Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Oct 2 19:36:44.073258 env[1739]: time="2023-10-02T19:36:44.073066249Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Oct 2 19:36:44.073330 env[1739]: time="2023-10-02T19:36:44.073292005Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Oct 2 19:36:44.073500 env[1739]: time="2023-10-02T19:36:44.073456345Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Oct 2 19:36:44.078009 env[1739]: time="2023-10-02T19:36:44.077946253Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Oct 2 19:36:44.078142 env[1739]: time="2023-10-02T19:36:44.078024241Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Oct 2 19:36:44.078142 env[1739]: time="2023-10-02T19:36:44.078060961Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Oct 2 19:36:44.078249 env[1739]: time="2023-10-02T19:36:44.078215833Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Oct 2 19:36:44.078333 env[1739]: time="2023-10-02T19:36:44.078252301Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Oct 2 19:36:44.078333 env[1739]: time="2023-10-02T19:36:44.078283825Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Oct 2 19:36:44.078333 env[1739]: time="2023-10-02T19:36:44.078312841Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Oct 2 19:36:44.078472 env[1739]: time="2023-10-02T19:36:44.078342505Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Oct 2 19:36:44.078472 env[1739]: time="2023-10-02T19:36:44.078371905Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Oct 2 19:36:44.078472 env[1739]: time="2023-10-02T19:36:44.078403669Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Oct 2 19:36:44.078472 env[1739]: time="2023-10-02T19:36:44.078432601Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Oct 2 19:36:44.078472 env[1739]: time="2023-10-02T19:36:44.078464317Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Oct 2 19:36:44.078997 env[1739]: time="2023-10-02T19:36:44.078755041Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Oct 2 19:36:44.078997 env[1739]: time="2023-10-02T19:36:44.078800845Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Oct 2 19:36:44.078997 env[1739]: time="2023-10-02T19:36:44.078833749Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Oct 2 19:36:44.078997 env[1739]: time="2023-10-02T19:36:44.078862285Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Oct 2 19:36:44.078997 env[1739]: time="2023-10-02T19:36:44.078900445Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1 Oct 2 19:36:44.078997 env[1739]: time="2023-10-02T19:36:44.078946009Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Oct 2 19:36:44.078997 env[1739]: time="2023-10-02T19:36:44.078982189Z" level=error msg="failed to initialize a tracing processor \"otlp\"" error="no OpenTelemetry endpoint: skip plugin" Oct 2 19:36:44.079345 env[1739]: time="2023-10-02T19:36:44.079055845Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Oct 2 19:36:44.083348 polkitd[1816]: Loading rules from directory /etc/polkit-1/rules.d Oct 2 19:36:44.083636 polkitd[1816]: Loading rules from directory /usr/share/polkit-1/rules.d Oct 2 19:36:44.085403 polkitd[1816]: Finished loading, compiling and executing 2 rules Oct 2 19:36:44.086326 dbus-daemon[1723]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Oct 2 19:36:44.086603 systemd[1]: Started polkit.service. Oct 2 19:36:44.088902 env[1739]: time="2023-10-02T19:36:44.079419397Z" 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}" Oct 2 19:36:44.088902 env[1739]: time="2023-10-02T19:36:44.088128853Z" level=info msg="Connect containerd service" Oct 2 19:36:44.091125 env[1739]: time="2023-10-02T19:36:44.088211377Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Oct 2 19:36:44.091783 polkitd[1816]: Acquired the name org.freedesktop.PolicyKit1 on the system bus Oct 2 19:36:44.092279 env[1739]: time="2023-10-02T19:36:44.092208997Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Oct 2 19:36:44.096092 env[1739]: time="2023-10-02T19:36:44.093154369Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Oct 2 19:36:44.096092 env[1739]: time="2023-10-02T19:36:44.093273277Z" level=info msg=serving... address=/run/containerd/containerd.sock Oct 2 19:36:44.096092 env[1739]: time="2023-10-02T19:36:44.095594965Z" level=info msg="containerd successfully booted in 0.285008s" Oct 2 19:36:44.093485 systemd[1]: Started containerd.service. Oct 2 19:36:44.110256 env[1739]: time="2023-10-02T19:36:44.110154517Z" level=info msg="Start subscribing containerd event" Oct 2 19:36:44.120538 systemd-hostnamed[1771]: Hostname set to (transient) Oct 2 19:36:44.120541 systemd-resolved[1691]: System hostname changed to 'ip-172-31-17-106'. Oct 2 19:36:44.127965 env[1739]: time="2023-10-02T19:36:44.127882561Z" level=info msg="Start recovering state" Oct 2 19:36:44.128142 env[1739]: time="2023-10-02T19:36:44.128096077Z" level=info msg="Start event monitor" Oct 2 19:36:44.128300 env[1739]: time="2023-10-02T19:36:44.128250745Z" level=info msg="Start snapshots syncer" Oct 2 19:36:44.135343 env[1739]: time="2023-10-02T19:36:44.135270769Z" level=info msg="Start cni network conf syncer for default" Oct 2 19:36:44.135343 env[1739]: time="2023-10-02T19:36:44.135330841Z" level=info msg="Start streaming server" Oct 2 19:36:44.298985 coreos-metadata[1720]: Oct 02 19:36:44.298 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Oct 2 19:36:44.307670 coreos-metadata[1720]: Oct 02 19:36:44.307 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/public-keys: Attempt #1 Oct 2 19:36:44.308460 coreos-metadata[1720]: Oct 02 19:36:44.308 INFO Fetch successful Oct 2 19:36:44.308586 coreos-metadata[1720]: Oct 02 19:36:44.308 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/public-keys/0/openssh-key: Attempt #1 Oct 2 19:36:44.309405 coreos-metadata[1720]: Oct 02 19:36:44.309 INFO Fetch successful Oct 2 19:36:44.312209 unknown[1720]: wrote ssh authorized keys file for user: core Oct 2 19:36:44.358373 update-ssh-keys[1886]: Updated "/home/core/.ssh/authorized_keys" Oct 2 19:36:44.359213 systemd[1]: Finished coreos-metadata-sshkeys@core.service. Oct 2 19:36:44.366602 systemd[1]: Starting docker.service... Oct 2 19:36:44.480298 amazon-ssm-agent[1718]: 2023-10-02 19:36:44 INFO Entering SSM Agent hibernate - AccessDeniedException: User: arn:aws:sts::075585003325:assumed-role/jenkins-test/i-08e765b05dd1c672f is not authorized to perform: ssm:UpdateInstanceInformation on resource: arn:aws:ec2:us-west-2:075585003325:instance/i-08e765b05dd1c672f because no identity-based policy allows the ssm:UpdateInstanceInformation action Oct 2 19:36:44.480298 amazon-ssm-agent[1718]: status code: 400, request id: 6920b72d-db91-4f36-996f-7627337778da Oct 2 19:36:44.480298 amazon-ssm-agent[1718]: 2023-10-02 19:36:44 INFO Agent is in hibernate mode. Reducing logging. Logging will be reduced to one log per backoff period Oct 2 19:36:44.682171 env[1892]: time="2023-10-02T19:36:44.682046944Z" level=info msg="Starting up" Oct 2 19:36:44.695304 env[1892]: time="2023-10-02T19:36:44.695237152Z" level=info msg="parsed scheme: \"unix\"" module=grpc Oct 2 19:36:44.695304 env[1892]: time="2023-10-02T19:36:44.695283772Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc Oct 2 19:36:44.695498 env[1892]: time="2023-10-02T19:36:44.695322844Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/libcontainerd/docker-containerd.sock 0 }] }" module=grpc Oct 2 19:36:44.695498 env[1892]: time="2023-10-02T19:36:44.695348464Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc Oct 2 19:36:44.702175 env[1892]: time="2023-10-02T19:36:44.702109132Z" level=info msg="parsed scheme: \"unix\"" module=grpc Oct 2 19:36:44.702175 env[1892]: time="2023-10-02T19:36:44.702154096Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc Oct 2 19:36:44.702371 env[1892]: time="2023-10-02T19:36:44.702190456Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/libcontainerd/docker-containerd.sock 0 }] }" module=grpc Oct 2 19:36:44.702371 env[1892]: time="2023-10-02T19:36:44.702212476Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc Oct 2 19:36:44.715308 systemd[1]: var-lib-docker-check\x2doverlayfs\x2dsupport4217939849-merged.mount: Deactivated successfully. Oct 2 19:36:44.785962 env[1892]: time="2023-10-02T19:36:44.785881900Z" level=info msg="Loading containers: start." Oct 2 19:36:45.034831 locksmithd[1772]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 2 19:36:45.152975 kernel: Initializing XFRM netlink socket Oct 2 19:36:45.197694 env[1892]: time="2023-10-02T19:36:45.197631698Z" 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" Oct 2 19:36:45.200899 (udev-worker)[1571]: Network interface NamePolicy= disabled on kernel command line. Oct 2 19:36:45.455339 systemd-networkd[1554]: docker0: Link UP Oct 2 19:36:45.482545 env[1892]: time="2023-10-02T19:36:45.482488468Z" level=info msg="Loading containers: done." Oct 2 19:36:45.509628 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck3701445094-merged.mount: Deactivated successfully. Oct 2 19:36:45.514999 env[1892]: time="2023-10-02T19:36:45.514948252Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 Oct 2 19:36:45.515497 env[1892]: time="2023-10-02T19:36:45.515465968Z" level=info msg="Docker daemon" commit=112bdf3343 graphdriver(s)=overlay2 version=20.10.23 Oct 2 19:36:45.515778 env[1892]: time="2023-10-02T19:36:45.515752912Z" level=info msg="Daemon has completed initialization" Oct 2 19:36:45.548528 systemd[1]: Started docker.service. Oct 2 19:36:45.559421 env[1892]: time="2023-10-02T19:36:45.559274068Z" level=info msg="API listen on /run/docker.sock" Oct 2 19:36:45.561963 etcd-wrapper[1834]: Error response from daemon: No such container: etcd-member Oct 2 19:36:45.630395 etcd-wrapper[2032]: Error: No such container: etcd-member Oct 2 19:36:45.756352 etcd-wrapper[2050]: Unable to find image 'quay.io/coreos/etcd:v3.5.0-arm64' locally Oct 2 19:36:47.219765 etcd-wrapper[2050]: v3.5.0-arm64: Pulling from coreos/etcd Oct 2 19:36:47.219765 etcd-wrapper[2050]: 6022c6b01d76: Pulling fs layer Oct 2 19:36:47.221299 etcd-wrapper[2050]: 1d97b1ec77fc: Pulling fs layer Oct 2 19:36:47.221632 etcd-wrapper[2050]: 99ef4b690837: Pulling fs layer Oct 2 19:36:47.223211 etcd-wrapper[2050]: 435b7bf0967c: Pulling fs layer Oct 2 19:36:47.223629 etcd-wrapper[2050]: 17e4a5f7c1b5: Pulling fs layer Oct 2 19:36:47.223869 etcd-wrapper[2050]: 5b0292999756: Pulling fs layer Oct 2 19:36:47.224120 etcd-wrapper[2050]: 435b7bf0967c: Waiting Oct 2 19:36:47.224346 etcd-wrapper[2050]: 17e4a5f7c1b5: Waiting Oct 2 19:36:47.224557 etcd-wrapper[2050]: 5b0292999756: Waiting Oct 2 19:36:47.514308 sshd_keygen[1745]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Oct 2 19:36:47.603733 systemd[1]: Finished sshd-keygen.service. Oct 2 19:36:47.608566 systemd[1]: Starting issuegen.service... Oct 2 19:36:47.632216 systemd[1]: issuegen.service: Deactivated successfully. Oct 2 19:36:47.632552 systemd[1]: Finished issuegen.service. Oct 2 19:36:47.637486 systemd[1]: Starting systemd-user-sessions.service... Oct 2 19:36:47.667057 systemd[1]: Finished systemd-user-sessions.service. Oct 2 19:36:47.672143 systemd[1]: Started getty@tty1.service. Oct 2 19:36:47.676704 systemd[1]: Started serial-getty@ttyS0.service. Oct 2 19:36:47.680022 systemd[1]: Reached target getty.target. Oct 2 19:36:47.895427 etcd-wrapper[2050]: 6022c6b01d76: Verifying Checksum Oct 2 19:36:47.895578 etcd-wrapper[2050]: 6022c6b01d76: Download complete Oct 2 19:36:47.973073 etcd-wrapper[2050]: 1d97b1ec77fc: Verifying Checksum Oct 2 19:36:47.973073 etcd-wrapper[2050]: 1d97b1ec77fc: Download complete Oct 2 19:36:47.982533 etcd-wrapper[2050]: 99ef4b690837: Verifying Checksum Oct 2 19:36:47.982533 etcd-wrapper[2050]: 99ef4b690837: Download complete Oct 2 19:36:48.351936 etcd-wrapper[2050]: 5b0292999756: Verifying Checksum Oct 2 19:36:48.352698 etcd-wrapper[2050]: 5b0292999756: Download complete Oct 2 19:36:48.379894 etcd-wrapper[2050]: 17e4a5f7c1b5: Verifying Checksum Oct 2 19:36:48.379894 etcd-wrapper[2050]: 17e4a5f7c1b5: Download complete Oct 2 19:36:48.574403 etcd-wrapper[2050]: 435b7bf0967c: Verifying Checksum Oct 2 19:36:48.574403 etcd-wrapper[2050]: 435b7bf0967c: Download complete Oct 2 19:36:49.817059 etcd-wrapper[2050]: 6022c6b01d76: Pull complete Oct 2 19:36:50.336096 systemd[1]: var-lib-docker-overlay2-eafc26cd52f3bb97976f83c1e7349af44a5cd283cf3ba56a86c21a006565ab9d-merged.mount: Deactivated successfully. Oct 2 19:36:50.766142 systemd[1]: Created slice system-sshd.slice. Oct 2 19:36:50.770576 systemd[1]: Started sshd@0-172.31.17.106:22-139.178.89.65:39026.service. Oct 2 19:36:50.889887 etcd-wrapper[2050]: 1d97b1ec77fc: Pull complete Oct 2 19:36:51.016241 sshd[2084]: Accepted publickey for core from 139.178.89.65 port 39026 ssh2: RSA SHA256:7JXBxnRlPbGQmmbR+r/0ht2yJ3EtkuLQ82x2+HEbSLE Oct 2 19:36:51.019441 sshd[2084]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 2 19:36:51.038426 systemd[1]: Created slice user-500.slice. Oct 2 19:36:51.042609 systemd[1]: Starting user-runtime-dir@500.service... Oct 2 19:36:51.056028 systemd-logind[1730]: New session 1 of user core. Oct 2 19:36:51.070276 systemd[1]: Finished user-runtime-dir@500.service. Oct 2 19:36:51.075251 systemd[1]: Starting user@500.service... Oct 2 19:36:51.091605 (systemd)[2094]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 2 19:36:51.319375 systemd[2094]: Queued start job for default target default.target. Oct 2 19:36:51.320858 systemd[2094]: Reached target paths.target. Oct 2 19:36:51.320900 systemd[2094]: Reached target sockets.target. Oct 2 19:36:51.320970 systemd[2094]: Reached target timers.target. Oct 2 19:36:51.321003 systemd[2094]: Reached target basic.target. Oct 2 19:36:51.321098 systemd[2094]: Reached target default.target. Oct 2 19:36:51.321162 systemd[2094]: Startup finished in 211ms. Oct 2 19:36:51.321180 systemd[1]: Started user@500.service. Oct 2 19:36:51.324725 systemd[1]: Started session-1.scope. Oct 2 19:36:51.450446 systemd[1]: var-lib-docker-overlay2-c11a98411b13998b0852889aae05f078dbd499b054f6e0dd50c2d26b5a820a6a-merged.mount: Deactivated successfully. Oct 2 19:36:51.482743 systemd[1]: Started sshd@1-172.31.17.106:22-139.178.89.65:39042.service. Oct 2 19:36:51.576653 etcd-wrapper[2050]: 99ef4b690837: Pull complete Oct 2 19:36:51.677812 sshd[2103]: Accepted publickey for core from 139.178.89.65 port 39042 ssh2: RSA SHA256:7JXBxnRlPbGQmmbR+r/0ht2yJ3EtkuLQ82x2+HEbSLE Oct 2 19:36:51.681297 sshd[2103]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 2 19:36:51.690687 systemd[1]: Started session-2.scope. Oct 2 19:36:51.693084 systemd-logind[1730]: New session 2 of user core. Oct 2 19:36:51.869162 sshd[2103]: pam_unix(sshd:session): session closed for user core Oct 2 19:36:51.876343 systemd[1]: session-2.scope: Deactivated successfully. Oct 2 19:36:51.877492 systemd[1]: sshd@1-172.31.17.106:22-139.178.89.65:39042.service: Deactivated successfully. Oct 2 19:36:51.879704 systemd-logind[1730]: Session 2 logged out. Waiting for processes to exit. Oct 2 19:36:51.881415 systemd-logind[1730]: Removed session 2. Oct 2 19:36:51.898559 systemd[1]: Started sshd@2-172.31.17.106:22-139.178.89.65:39046.service. Oct 2 19:36:52.017479 systemd[1]: var-lib-docker-overlay2-e140df1a4277758a68876774e0fbdd9d2fdb5220b5636c215d42903cb90b39f0-merged.mount: Deactivated successfully. Oct 2 19:36:52.082299 sshd[2115]: Accepted publickey for core from 139.178.89.65 port 39046 ssh2: RSA SHA256:7JXBxnRlPbGQmmbR+r/0ht2yJ3EtkuLQ82x2+HEbSLE Oct 2 19:36:52.085562 sshd[2115]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 2 19:36:52.095098 systemd[1]: Started session-3.scope. Oct 2 19:36:52.097776 systemd-logind[1730]: New session 3 of user core. Oct 2 19:36:52.103652 etcd-wrapper[2050]: 435b7bf0967c: Pull complete Oct 2 19:36:52.237867 systemd[1]: var-lib-docker-overlay2-db19d14b7e3a952f1a397b09200c07e0b6ea6cc8156cda1b600a2f5929cf44e6-merged.mount: Deactivated successfully. Oct 2 19:36:52.251415 sshd[2115]: pam_unix(sshd:session): session closed for user core Oct 2 19:36:52.256183 systemd[1]: session-3.scope: Deactivated successfully. Oct 2 19:36:52.257309 systemd[1]: sshd@2-172.31.17.106:22-139.178.89.65:39046.service: Deactivated successfully. Oct 2 19:36:52.258904 systemd-logind[1730]: Session 3 logged out. Waiting for processes to exit. Oct 2 19:36:52.260697 systemd-logind[1730]: Removed session 3. Oct 2 19:36:52.280230 etcd-wrapper[2050]: 17e4a5f7c1b5: Pull complete Oct 2 19:36:52.393284 systemd[1]: var-lib-docker-overlay2-32ef87b4e67cf7ec0c7c1c88aadf85b56a153a1267c03b179b6b9774969496ef-merged.mount: Deactivated successfully. Oct 2 19:36:52.408500 etcd-wrapper[2050]: 5b0292999756: Pull complete Oct 2 19:36:52.418235 etcd-wrapper[2050]: Digest: sha256:96255d1ad67e7afcea46b4dc1b7e74024b9f6971193cb7316b093b3b5671efb0 Oct 2 19:36:52.421740 etcd-wrapper[2050]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.0-arm64 Oct 2 19:36:52.626559 env[1739]: time="2023-10-02T19:36:52.626312987Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 2 19:36:52.626559 env[1739]: time="2023-10-02T19:36:52.626401943Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 2 19:36:52.626559 env[1739]: time="2023-10-02T19:36:52.626430275Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 2 19:36:52.628283 env[1739]: time="2023-10-02T19:36:52.628159631Z" level=info msg="starting signal loop" namespace=moby path=/run/containerd/io.containerd.runtime.v2.task/moby/ee1e6c3333ab3e967a3f605df49b66afa9790a9cb9d21057656f24681a398837 pid=2148 runtime=io.containerd.runc.v2 Oct 2 19:36:52.661334 systemd[1]: Started docker-ee1e6c3333ab3e967a3f605df49b66afa9790a9cb9d21057656f24681a398837.scope. Oct 2 19:36:52.793394 etcd-wrapper[2050]: {"level":"info","ts":1696275412.7928505,"caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} Oct 2 19:36:52.793584 etcd-wrapper[2050]: {"level":"info","ts":1696275412.793149,"caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"ec2fc7b1f3c281e1983e7d6efb389207"} Oct 2 19:36:52.793877 etcd-wrapper[2050]: {"level":"warn","ts":1696275412.7936234,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} Oct 2 19:36:52.793877 etcd-wrapper[2050]: {"level":"warn","ts":1696275412.7936597,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.0-arm64"} Oct 2 19:36:52.794242 etcd-wrapper[2050]: {"level":"warn","ts":1696275412.79391,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} Oct 2 19:36:52.794242 etcd-wrapper[2050]: {"level":"warn","ts":1696275412.7939713,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} Oct 2 19:36:52.794242 etcd-wrapper[2050]: {"level":"warn","ts":1696275412.793991,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_UNSUPPORTED_ARCH=arm64"} Oct 2 19:36:52.794680 etcd-wrapper[2050]: {"level":"info","ts":"2023-10-02T19:36:52.794Z","caller":"etcdmain/etcd.go:72","msg":"Running: ","args":["/usr/local/bin/etcd","--listen-client-urls=http://0.0.0.0:2379","--advertise-client-urls=http://172.31.17.106:2379"]} Oct 2 19:36:52.795006 etcd-wrapper[2050]: {"level":"info","ts":"2023-10-02T19:36:52.794Z","caller":"embed/etcd.go:131","msg":"configuring peer listeners","listen-peer-urls":["http://localhost:2380"]} Oct 2 19:36:52.796264 etcd-wrapper[2050]: {"level":"info","ts":"2023-10-02T19:36:52.795Z","caller":"embed/etcd.go:139","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} Oct 2 19:36:52.796981 etcd-wrapper[2050]: {"level":"info","ts":"2023-10-02T19:36:52.796Z","caller":"embed/etcd.go:307","msg":"starting an etcd server","etcd-version":"3.5.0","git-sha":"946a5a6f2","go-version":"go1.16.3","go-os":"linux","go-arch":"arm64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"ec2fc7b1f3c281e1983e7d6efb389207","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,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["http://localhost:2380"],"listen-peer-urls":["http://localhost:2380"],"advertise-client-urls":["http://172.31.17.106:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"ec2fc7b1f3c281e1983e7d6efb389207=http://localhost:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-size-bytes":2147483648,"pre-vote":true,"initial-corrupt-check":false,"corrupt-check-time-interval":"0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} Oct 2 19:36:52.800505 etcd-wrapper[2050]: {"level":"info","ts":"2023-10-02T19:36:52.800Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.656524ms"} Oct 2 19:36:52.807142 etcd-wrapper[2050]: {"level":"info","ts":"2023-10-02T19:36:52.806Z","caller":"etcdserver/raft.go:448","msg":"starting local member","local-member-id":"8e9e05c52164694d","cluster-id":"cdf818194e3a8c32"} Oct 2 19:36:52.807606 etcd-wrapper[2050]: {"level":"info","ts":"2023-10-02T19:36:52.807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=()"} Oct 2 19:36:52.807903 etcd-wrapper[2050]: {"level":"info","ts":"2023-10-02T19:36:52.807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 0"} Oct 2 19:36:52.808242 etcd-wrapper[2050]: {"level":"info","ts":"2023-10-02T19:36:52.807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8e9e05c52164694d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} Oct 2 19:36:52.808507 etcd-wrapper[2050]: {"level":"info","ts":"2023-10-02T19:36:52.808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 1"} Oct 2 19:36:52.808880 etcd-wrapper[2050]: {"level":"info","ts":"2023-10-02T19:36:52.808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Oct 2 19:36:52.813219 etcd-wrapper[2050]: {"level":"warn","ts":"2023-10-02T19:36:52.812Z","caller":"auth/store.go:1220","msg":"simple token is not cryptographically signed"} Oct 2 19:36:52.815861 etcd-wrapper[2050]: {"level":"info","ts":"2023-10-02T19:36:52.815Z","caller":"mvcc/kvstore.go:415","msg":"kvstore restored","current-rev":1} Oct 2 19:36:52.817639 etcd-wrapper[2050]: {"level":"info","ts":"2023-10-02T19:36:52.817Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} Oct 2 19:36:52.819245 etcd-wrapper[2050]: {"level":"info","ts":"2023-10-02T19:36:52.819Z","caller":"etcdserver/server.go:843","msg":"starting etcd server","local-member-id":"8e9e05c52164694d","local-server-version":"3.5.0","cluster-version":"to_be_decided"} Oct 2 19:36:52.820894 etcd-wrapper[2050]: {"level":"info","ts":"2023-10-02T19:36:52.820Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Oct 2 19:36:52.821388 etcd-wrapper[2050]: {"level":"info","ts":"2023-10-02T19:36:52.821Z","caller":"membership/cluster.go:393","msg":"added member","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","added-peer-id":"8e9e05c52164694d","added-peer-peer-urls":["http://localhost:2380"]} Oct 2 19:36:52.821874 etcd-wrapper[2050]: {"level":"info","ts":"2023-10-02T19:36:52.821Z","caller":"etcdserver/server.go:728","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8e9e05c52164694d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} Oct 2 19:36:52.826963 etcd-wrapper[2050]: {"level":"info","ts":"2023-10-02T19:36:52.826Z","caller":"embed/etcd.go:276","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.17.106:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} Oct 2 19:36:52.826963 etcd-wrapper[2050]: {"level":"info","ts":"2023-10-02T19:36:52.826Z","caller":"embed/etcd.go:580","msg":"serving peer traffic","address":"127.0.0.1:2380"} Oct 2 19:36:52.827204 etcd-wrapper[2050]: {"level":"info","ts":"2023-10-02T19:36:52.826Z","caller":"embed/etcd.go:552","msg":"cmux::serve","address":"127.0.0.1:2380"} Oct 2 19:36:53.311000 etcd-wrapper[2050]: {"level":"info","ts":"2023-10-02T19:36:53.310Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d is starting a new election at term 1"} Oct 2 19:36:53.311858 etcd-wrapper[2050]: {"level":"info","ts":"2023-10-02T19:36:53.310Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became pre-candidate at term 1"} Oct 2 19:36:53.311858 etcd-wrapper[2050]: {"level":"info","ts":"2023-10-02T19:36:53.311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgPreVoteResp from 8e9e05c52164694d at term 1"} Oct 2 19:36:53.311858 etcd-wrapper[2050]: {"level":"info","ts":"2023-10-02T19:36:53.311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became candidate at term 2"} Oct 2 19:36:53.311858 etcd-wrapper[2050]: {"level":"info","ts":"2023-10-02T19:36:53.311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 2"} Oct 2 19:36:53.311858 etcd-wrapper[2050]: {"level":"info","ts":"2023-10-02T19:36:53.311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became leader at term 2"} Oct 2 19:36:53.311858 etcd-wrapper[2050]: {"level":"info","ts":"2023-10-02T19:36:53.311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 2"} Oct 2 19:36:53.312556 etcd-wrapper[2050]: {"level":"info","ts":"2023-10-02T19:36:53.311Z","caller":"etcdserver/server.go:2027","msg":"published local member to cluster through raft","local-member-id":"8e9e05c52164694d","local-member-attributes":"{Name:ec2fc7b1f3c281e1983e7d6efb389207 ClientURLs:[http://172.31.17.106:2379]}","request-path":"/0/members/8e9e05c52164694d/attributes","cluster-id":"cdf818194e3a8c32","publish-timeout":"7s"} Oct 2 19:36:53.312822 etcd-wrapper[2050]: {"level":"info","ts":"2023-10-02T19:36:53.312Z","caller":"etcdserver/server.go:2476","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} Oct 2 19:36:53.313218 etcd-wrapper[2050]: {"level":"info","ts":"2023-10-02T19:36:53.312Z","caller":"etcdmain/main.go:47","msg":"notifying init daemon"} Oct 2 19:36:53.313519 systemd[1]: Started etcd-member.service. Oct 2 19:36:53.314115 systemd[1]: Reached target multi-user.target. Oct 2 19:36:53.316812 systemd[1]: Starting systemd-update-utmp-runlevel.service... Oct 2 19:36:53.320213 etcd-wrapper[2050]: {"level":"info","ts":"2023-10-02T19:36:53.313Z","caller":"etcdmain/main.go:53","msg":"successfully notified init daemon"} Oct 2 19:36:53.320213 etcd-wrapper[2050]: {"level":"info","ts":"2023-10-02T19:36:53.312Z","caller":"embed/serve.go:98","msg":"ready to serve client requests"} Oct 2 19:36:53.320213 etcd-wrapper[2050]: {"level":"info","ts":"2023-10-02T19:36:53.315Z","caller":"membership/cluster.go:531","msg":"set initial cluster version","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","cluster-version":"3.5"} Oct 2 19:36:53.320213 etcd-wrapper[2050]: {"level":"info","ts":"2023-10-02T19:36:53.316Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} Oct 2 19:36:53.320213 etcd-wrapper[2050]: {"level":"info","ts":"2023-10-02T19:36:53.316Z","caller":"etcdserver/server.go:2500","msg":"cluster version is updated","cluster-version":"3.5"} Oct 2 19:36:53.320213 etcd-wrapper[2050]: {"level":"info","ts":"2023-10-02T19:36:53.317Z","caller":"embed/serve.go:140","msg":"serving client traffic insecurely; this is strongly discouraged!","address":"[::]:2379"} Oct 2 19:36:53.339882 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 2 19:36:53.340314 systemd[1]: Finished systemd-update-utmp-runlevel.service. Oct 2 19:36:53.340648 systemd[1]: Startup finished in 1.187s (kernel) + 12.319s (initrd) + 23.889s (userspace) = 37.396s. Oct 2 19:37:02.282841 systemd[1]: Started sshd@3-172.31.17.106:22-139.178.89.65:50762.service. Oct 2 19:37:02.462501 sshd[2189]: Accepted publickey for core from 139.178.89.65 port 50762 ssh2: RSA SHA256:7JXBxnRlPbGQmmbR+r/0ht2yJ3EtkuLQ82x2+HEbSLE Oct 2 19:37:02.465697 sshd[2189]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 2 19:37:02.474765 systemd-logind[1730]: New session 4 of user core. Oct 2 19:37:02.474896 systemd[1]: Started session-4.scope. Oct 2 19:37:02.622187 sshd[2189]: pam_unix(sshd:session): session closed for user core Oct 2 19:37:02.629982 systemd-logind[1730]: Session 4 logged out. Waiting for processes to exit. Oct 2 19:37:02.630375 systemd[1]: sshd@3-172.31.17.106:22-139.178.89.65:50762.service: Deactivated successfully. Oct 2 19:37:02.631588 systemd[1]: session-4.scope: Deactivated successfully. Oct 2 19:37:02.633156 systemd-logind[1730]: Removed session 4. Oct 2 19:37:02.651453 systemd[1]: Started sshd@4-172.31.17.106:22-139.178.89.65:50772.service. Oct 2 19:37:02.827486 sshd[2195]: Accepted publickey for core from 139.178.89.65 port 50772 ssh2: RSA SHA256:7JXBxnRlPbGQmmbR+r/0ht2yJ3EtkuLQ82x2+HEbSLE Oct 2 19:37:02.830708 sshd[2195]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 2 19:37:02.838843 systemd-logind[1730]: New session 5 of user core. Oct 2 19:37:02.839715 systemd[1]: Started session-5.scope. Oct 2 19:37:02.970768 sshd[2195]: pam_unix(sshd:session): session closed for user core Oct 2 19:37:02.976302 systemd-logind[1730]: Session 5 logged out. Waiting for processes to exit. Oct 2 19:37:02.976487 systemd[1]: session-5.scope: Deactivated successfully. Oct 2 19:37:02.977619 systemd[1]: sshd@4-172.31.17.106:22-139.178.89.65:50772.service: Deactivated successfully. Oct 2 19:37:02.979654 systemd-logind[1730]: Removed session 5. Oct 2 19:37:03.002220 systemd[1]: Started sshd@5-172.31.17.106:22-139.178.89.65:50784.service. Oct 2 19:37:03.181380 sshd[2201]: Accepted publickey for core from 139.178.89.65 port 50784 ssh2: RSA SHA256:7JXBxnRlPbGQmmbR+r/0ht2yJ3EtkuLQ82x2+HEbSLE Oct 2 19:37:03.184626 sshd[2201]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 2 19:37:03.192883 systemd-logind[1730]: New session 6 of user core. Oct 2 19:37:03.193742 systemd[1]: Started session-6.scope. Oct 2 19:37:03.340794 sshd[2201]: pam_unix(sshd:session): session closed for user core Oct 2 19:37:03.346779 systemd[1]: sshd@5-172.31.17.106:22-139.178.89.65:50784.service: Deactivated successfully. Oct 2 19:37:03.348026 systemd[1]: session-6.scope: Deactivated successfully. Oct 2 19:37:03.348064 systemd-logind[1730]: Session 6 logged out. Waiting for processes to exit. Oct 2 19:37:03.350038 systemd-logind[1730]: Removed session 6. Oct 2 19:37:03.370651 systemd[1]: Started sshd@6-172.31.17.106:22-139.178.89.65:50786.service. Oct 2 19:37:03.548088 sshd[2207]: Accepted publickey for core from 139.178.89.65 port 50786 ssh2: RSA SHA256:7JXBxnRlPbGQmmbR+r/0ht2yJ3EtkuLQ82x2+HEbSLE Oct 2 19:37:03.551774 sshd[2207]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 2 19:37:03.560072 systemd-logind[1730]: New session 7 of user core. Oct 2 19:37:03.560497 systemd[1]: Started session-7.scope. Oct 2 19:37:03.689798 sudo[2210]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Oct 2 19:37:03.690990 sudo[2210]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 2 19:37:03.705171 dbus-daemon[1723]: avc: received setenforce notice (enforcing=1) Oct 2 19:37:03.708133 sudo[2210]: pam_unix(sudo:session): session closed for user root Oct 2 19:37:03.733045 sshd[2207]: pam_unix(sshd:session): session closed for user core Oct 2 19:37:03.738771 systemd-logind[1730]: Session 7 logged out. Waiting for processes to exit. Oct 2 19:37:03.739455 systemd[1]: sshd@6-172.31.17.106:22-139.178.89.65:50786.service: Deactivated successfully. Oct 2 19:37:03.740721 systemd[1]: session-7.scope: Deactivated successfully. Oct 2 19:37:03.742285 systemd-logind[1730]: Removed session 7. Oct 2 19:37:03.763738 systemd[1]: Started sshd@7-172.31.17.106:22-139.178.89.65:50788.service. Oct 2 19:37:03.945087 sshd[2214]: Accepted publickey for core from 139.178.89.65 port 50788 ssh2: RSA SHA256:7JXBxnRlPbGQmmbR+r/0ht2yJ3EtkuLQ82x2+HEbSLE Oct 2 19:37:03.947782 sshd[2214]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 2 19:37:03.956962 systemd[1]: Started session-8.scope. Oct 2 19:37:03.957699 systemd-logind[1730]: New session 8 of user core. Oct 2 19:37:04.078902 sudo[2218]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Oct 2 19:37:04.080034 sudo[2218]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 2 19:37:04.087868 sudo[2218]: pam_unix(sudo:session): session closed for user root Oct 2 19:37:04.101619 sudo[2217]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/systemctl restart audit-rules Oct 2 19:37:04.102136 sudo[2217]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 2 19:37:04.125604 systemd[1]: Stopping audit-rules.service... Oct 2 19:37:04.134575 kernel: kauditd_printk_skb: 81 callbacks suppressed Oct 2 19:37:04.134705 kernel: audit: type=1305 audit(1696275424.129:170): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Oct 2 19:37:04.129000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Oct 2 19:37:04.129000 audit[2221]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffe5e6a4e0 a2=420 a3=0 items=0 ppid=1 pid=2221 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) Oct 2 19:37:04.138393 auditctl[2221]: No rules Oct 2 19:37:04.139467 systemd[1]: audit-rules.service: Deactivated successfully. Oct 2 19:37:04.139842 systemd[1]: Stopped audit-rules.service. Oct 2 19:37:04.149066 kernel: audit: type=1300 audit(1696275424.129:170): arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffe5e6a4e0 a2=420 a3=0 items=0 ppid=1 pid=2221 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) Oct 2 19:37:04.150327 systemd[1]: Starting audit-rules.service... Oct 2 19:37:04.129000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D44 Oct 2 19:37:04.155535 kernel: audit: type=1327 audit(1696275424.129:170): proctitle=2F7362696E2F617564697463746C002D44 Oct 2 19:37:04.137000 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' Oct 2 19:37:04.163796 kernel: audit: type=1131 audit(1696275424.137:171): 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' Oct 2 19:37:04.212027 augenrules[2238]: No rules Oct 2 19:37:04.214813 systemd[1]: Finished audit-rules.service. Oct 2 19:37:04.214000 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' Oct 2 19:37:04.225136 kernel: audit: type=1130 audit(1696275424.214:172): 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' Oct 2 19:37:04.225116 sudo[2217]: pam_unix(sudo:session): session closed for user root Oct 2 19:37:04.224000 audit[2217]: USER_END pid=2217 uid=500 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Oct 2 19:37:04.224000 audit[2217]: CRED_DISP pid=2217 uid=500 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Oct 2 19:37:04.234945 kernel: audit: type=1106 audit(1696275424.224:173): pid=2217 uid=500 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Oct 2 19:37:04.243965 kernel: audit: type=1104 audit(1696275424.224:174): pid=2217 uid=500 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Oct 2 19:37:04.249285 sshd[2214]: pam_unix(sshd:session): session closed for user core Oct 2 19:37:04.250000 audit[2214]: USER_END pid=2214 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' Oct 2 19:37:04.254989 systemd-logind[1730]: Session 8 logged out. Waiting for processes to exit. Oct 2 19:37:04.256031 systemd[1]: session-8.scope: Deactivated successfully. Oct 2 19:37:04.257093 systemd[1]: sshd@7-172.31.17.106:22-139.178.89.65:50788.service: Deactivated successfully. Oct 2 19:37:04.259643 systemd-logind[1730]: Removed session 8. Oct 2 19:37:04.250000 audit[2214]: CRED_DISP pid=2214 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' Oct 2 19:37:04.273834 kernel: audit: type=1106 audit(1696275424.250:175): pid=2214 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' Oct 2 19:37:04.277681 kernel: audit: type=1104 audit(1696275424.250:176): pid=2214 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' Oct 2 19:37:04.277750 kernel: audit: type=1131 audit(1696275424.256:177): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@7-172.31.17.106:22-139.178.89.65:50788 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:37:04.256000 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.17.106:22-139.178.89.65:50788 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:37:04.285542 systemd[1]: Started sshd@8-172.31.17.106:22-139.178.89.65:50798.service. Oct 2 19:37:04.285000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@8-172.31.17.106:22-139.178.89.65:50798 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:37:04.454000 audit[2244]: USER_ACCT pid=2244 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' Oct 2 19:37:04.455702 sshd[2244]: Accepted publickey for core from 139.178.89.65 port 50798 ssh2: RSA SHA256:7JXBxnRlPbGQmmbR+r/0ht2yJ3EtkuLQ82x2+HEbSLE Oct 2 19:37:04.457000 audit[2244]: CRED_ACQ pid=2244 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' Oct 2 19:37:04.457000 audit[2244]: SYSCALL arch=c00000b7 syscall=64 success=yes exit=3 a0=5 a1=ffffcee87410 a2=3 a3=1 items=0 ppid=1 pid=2244 auid=500 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=9 comm="sshd" exe="/usr/sbin/sshd" subj=system_u:system_r:kernel_t:s0 key=(null) Oct 2 19:37:04.457000 audit: PROCTITLE proctitle=737368643A20636F7265205B707269765D Oct 2 19:37:04.459128 sshd[2244]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 2 19:37:04.467608 systemd[1]: Started session-9.scope. Oct 2 19:37:04.468356 systemd-logind[1730]: New session 9 of user core. Oct 2 19:37:04.477000 audit[2244]: USER_START pid=2244 uid=0 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_open grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=139.178.89.65 addr=139.178.89.65 terminal=ssh res=success' Oct 2 19:37:04.484000 audit[2246]: CRED_ACQ pid=2246 uid=0 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=139.178.89.65 addr=139.178.89.65 terminal=ssh res=success' Oct 2 19:37:04.734665 sshd[2244]: pam_unix(sshd:session): session closed for user core Oct 2 19:37:04.735000 audit[2244]: USER_END pid=2244 uid=0 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=139.178.89.65 addr=139.178.89.65 terminal=ssh res=success' Oct 2 19:37:04.736000 audit[2244]: CRED_DISP pid=2244 uid=0 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=139.178.89.65 addr=139.178.89.65 terminal=ssh res=success' Oct 2 19:37:04.740698 systemd[1]: sshd@8-172.31.17.106:22-139.178.89.65:50798.service: Deactivated successfully. Oct 2 19:37:04.740000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@8-172.31.17.106:22-139.178.89.65:50798 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:37:04.742487 systemd[1]: session-9.scope: Deactivated successfully. Oct 2 19:37:04.743952 systemd-logind[1730]: Session 9 logged out. Waiting for processes to exit. Oct 2 19:37:04.746037 systemd-logind[1730]: Removed session 9. Oct 2 19:37:14.142234 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Oct 2 19:37:14.141000 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' Oct 2 19:37:14.144884 kernel: kauditd_printk_skb: 11 callbacks suppressed Oct 2 19:37:14.144984 kernel: audit: type=1131 audit(1696275434.141:187): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-hostnamed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:37:14.176000 audit: BPF prog-id=37 op=UNLOAD Oct 2 19:37:14.176000 audit: BPF prog-id=36 op=UNLOAD Oct 2 19:37:14.182297 kernel: audit: type=1334 audit(1696275434.176:188): prog-id=37 op=UNLOAD Oct 2 19:37:14.182374 kernel: audit: type=1334 audit(1696275434.176:189): prog-id=36 op=UNLOAD Oct 2 19:37:14.182415 kernel: audit: type=1334 audit(1696275434.176:190): prog-id=35 op=UNLOAD Oct 2 19:37:14.176000 audit: BPF prog-id=35 op=UNLOAD Oct 2 19:37:28.959008 update_engine[1731]: I1002 19:37:28.958937 1731 update_attempter.cc:505] Updating boot flags... Oct 2 19:41:44.480138 amazon-ssm-agent[1718]: 2023-10-02 19:41:44 INFO Backing off health check to every 600 seconds for 1800 seconds. Oct 2 19:41:44.581364 amazon-ssm-agent[1718]: 2023-10-02 19:41:44 ERROR Health ping failed with error - AccessDeniedException: User: arn:aws:sts::075585003325:assumed-role/jenkins-test/i-08e765b05dd1c672f is not authorized to perform: ssm:UpdateInstanceInformation on resource: arn:aws:ec2:us-west-2:075585003325:instance/i-08e765b05dd1c672f because no identity-based policy allows the ssm:UpdateInstanceInformation action Oct 2 19:41:44.581364 amazon-ssm-agent[1718]: status code: 400, request id: 56a623e8-b0a7-4d3d-b83c-881599c46cfa Oct 2 19:43:20.976984 update_engine[1731]: I1002 19:43:20.976897 1731 prefs.cc:51] certificate-report-to-send-update not present in /var/lib/update_engine/prefs Oct 2 19:43:20.976984 update_engine[1731]: I1002 19:43:20.976986 1731 prefs.cc:51] certificate-report-to-send-download not present in /var/lib/update_engine/prefs Oct 2 19:43:20.977741 update_engine[1731]: I1002 19:43:20.977387 1731 prefs.cc:51] aleph-version not present in /var/lib/update_engine/prefs Oct 2 19:43:20.978970 update_engine[1731]: I1002 19:43:20.978729 1731 omaha_request_params.cc:62] Current group set to lts Oct 2 19:43:20.979583 update_engine[1731]: I1002 19:43:20.979280 1731 update_attempter.cc:495] Already updated boot flags. Skipping. Oct 2 19:43:20.979583 update_engine[1731]: I1002 19:43:20.979302 1731 update_attempter.cc:638] Scheduling an action processor start. Oct 2 19:43:20.979583 update_engine[1731]: I1002 19:43:20.979331 1731 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Oct 2 19:43:20.979583 update_engine[1731]: I1002 19:43:20.979377 1731 prefs.cc:51] previous-version not present in /var/lib/update_engine/prefs Oct 2 19:43:20.981367 update_engine[1731]: I1002 19:43:20.981309 1731 omaha_request_action.cc:268] Posting an Omaha request to https://public.update.flatcar-linux.net/v1/update/ Oct 2 19:43:20.981367 update_engine[1731]: I1002 19:43:20.981352 1731 omaha_request_action.cc:269] Request: Oct 2 19:43:20.981367 update_engine[1731]: Oct 2 19:43:20.981367 update_engine[1731]: Oct 2 19:43:20.981367 update_engine[1731]: Oct 2 19:43:20.981367 update_engine[1731]: Oct 2 19:43:20.981367 update_engine[1731]: Oct 2 19:43:20.981367 update_engine[1731]: Oct 2 19:43:20.981367 update_engine[1731]: Oct 2 19:43:20.981367 update_engine[1731]: Oct 2 19:43:20.981367 update_engine[1731]: I1002 19:43:20.981370 1731 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Oct 2 19:43:20.983619 locksmithd[1772]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_CHECKING_FOR_UPDATE" NewVersion=0.0.0 NewSize=0 Oct 2 19:43:20.989707 update_engine[1731]: I1002 19:43:20.989648 1731 libcurl_http_fetcher.cc:174] Setting up curl options for HTTPS Oct 2 19:43:20.990059 update_engine[1731]: I1002 19:43:20.990002 1731 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Oct 2 19:43:22.169963 update_engine[1731]: I1002 19:43:22.169879 1731 prefs.cc:51] update-server-cert-0-2 not present in /var/lib/update_engine/prefs Oct 2 19:43:22.170477 update_engine[1731]: I1002 19:43:22.170302 1731 prefs.cc:51] update-server-cert-0-1 not present in /var/lib/update_engine/prefs Oct 2 19:43:22.170642 update_engine[1731]: I1002 19:43:22.170598 1731 prefs.cc:51] update-server-cert-0-0 not present in /var/lib/update_engine/prefs Oct 2 19:43:22.490095 update_engine[1731]: I1002 19:43:22.490038 1731 libcurl_http_fetcher.cc:263] HTTP response code: 200 Oct 2 19:43:22.492151 update_engine[1731]: I1002 19:43:22.492089 1731 libcurl_http_fetcher.cc:320] Transfer completed (200), 314 bytes downloaded Oct 2 19:43:22.492151 update_engine[1731]: I1002 19:43:22.492134 1731 omaha_request_action.cc:619] Omaha request response: Oct 2 19:43:22.492151 update_engine[1731]: Oct 2 19:43:22.500087 update_engine[1731]: I1002 19:43:22.500026 1731 omaha_request_action.cc:409] No update. Oct 2 19:43:22.500087 update_engine[1731]: I1002 19:43:22.500079 1731 action_processor.cc:82] ActionProcessor::ActionComplete: finished OmahaRequestAction, starting OmahaResponseHandlerAction Oct 2 19:43:22.500087 update_engine[1731]: I1002 19:43:22.500094 1731 omaha_response_handler_action.cc:36] There are no updates. Aborting. Oct 2 19:43:22.500559 update_engine[1731]: I1002 19:43:22.500104 1731 action_processor.cc:68] ActionProcessor::ActionComplete: OmahaResponseHandlerAction action failed. Aborting processing. Oct 2 19:43:22.500559 update_engine[1731]: I1002 19:43:22.500113 1731 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaResponseHandlerAction Oct 2 19:43:22.500559 update_engine[1731]: I1002 19:43:22.500123 1731 update_attempter.cc:302] Processing Done. Oct 2 19:43:22.500559 update_engine[1731]: I1002 19:43:22.500144 1731 update_attempter.cc:338] No update. Oct 2 19:43:22.500559 update_engine[1731]: I1002 19:43:22.500167 1731 update_check_scheduler.cc:74] Next update check in 45m34s Oct 2 19:43:22.500829 locksmithd[1772]: LastCheckedTime=1696275802 Progress=0 CurrentOperation="UPDATE_STATUS_IDLE" NewVersion=0.0.0 NewSize=0