Oct 2 18:46:38.206034 kernel: Booting Linux on physical CPU 0x0000000000 [0x410fd083]
Oct 2 18:46:38.206073 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 18:46:38.206097 kernel: efi: EFI v2.70 by EDK II
Oct 2 18:46:38.206112 kernel: efi: SMBIOS=0x7bed0000 SMBIOS 3.0=0x7beb0000 ACPI=0x786e0000 ACPI 2.0=0x786e0014 MEMATTR=0x7ac1aa98 MEMRESERVE=0x71accf98
Oct 2 18:46:38.206126 kernel: ACPI: Early table checksum verification disabled
Oct 2 18:46:38.206140 kernel: ACPI: RSDP 0x00000000786E0014 000024 (v02 AMAZON)
Oct 2 18:46:38.206156 kernel: ACPI: XSDT 0x00000000786D00E8 000064 (v01 AMAZON AMZNFACP 00000001 01000013)
Oct 2 18:46:38.206171 kernel: ACPI: FACP 0x00000000786B0000 000114 (v06 AMAZON AMZNFACP 00000001 AMZN 00000001)
Oct 2 18:46:38.206185 kernel: ACPI: DSDT 0x0000000078640000 00154F (v02 AMAZON AMZNDSDT 00000001 INTL 20160527)
Oct 2 18:46:38.206199 kernel: ACPI: APIC 0x00000000786C0000 000108 (v04 AMAZON AMZNAPIC 00000001 AMZN 00000001)
Oct 2 18:46:38.206217 kernel: ACPI: SPCR 0x00000000786A0000 000050 (v02 AMAZON AMZNSPCR 00000001 AMZN 00000001)
Oct 2 18:46:38.206231 kernel: ACPI: GTDT 0x0000000078690000 000060 (v02 AMAZON AMZNGTDT 00000001 AMZN 00000001)
Oct 2 18:46:38.206245 kernel: ACPI: MCFG 0x0000000078680000 00003C (v02 AMAZON AMZNMCFG 00000001 AMZN 00000001)
Oct 2 18:46:38.206260 kernel: ACPI: SLIT 0x0000000078670000 00002D (v01 AMAZON AMZNSLIT 00000001 AMZN 00000001)
Oct 2 18:46:38.206276 kernel: ACPI: IORT 0x0000000078660000 000078 (v01 AMAZON AMZNIORT 00000001 AMZN 00000001)
Oct 2 18:46:38.206295 kernel: ACPI: PPTT 0x0000000078650000 0000EC (v01 AMAZON AMZNPPTT 00000001 AMZN 00000001)
Oct 2 18:46:38.206310 kernel: ACPI: SPCR: console: uart,mmio,0x90a0000,115200
Oct 2 18:46:38.206325 kernel: earlycon: uart0 at MMIO 0x00000000090a0000 (options '115200')
Oct 2 18:46:38.206339 kernel: printk: bootconsole [uart0] enabled
Oct 2 18:46:38.206354 kernel: NUMA: Failed to initialise from firmware
Oct 2 18:46:38.206369 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000004b5ffffff]
Oct 2 18:46:38.206384 kernel: NUMA: NODE_DATA [mem 0x4b5841900-0x4b5846fff]
Oct 2 18:46:38.206398 kernel: Zone ranges:
Oct 2 18:46:38.206413 kernel: DMA [mem 0x0000000040000000-0x00000000ffffffff]
Oct 2 18:46:38.206428 kernel: DMA32 empty
Oct 2 18:46:38.206443 kernel: Normal [mem 0x0000000100000000-0x00000004b5ffffff]
Oct 2 18:46:38.206461 kernel: Movable zone start for each node
Oct 2 18:46:38.206476 kernel: Early memory node ranges
Oct 2 18:46:38.206491 kernel: node 0: [mem 0x0000000040000000-0x00000000786effff]
Oct 2 18:46:38.206506 kernel: node 0: [mem 0x00000000786f0000-0x000000007872ffff]
Oct 2 18:46:38.206520 kernel: node 0: [mem 0x0000000078730000-0x000000007bbfffff]
Oct 2 18:46:38.206535 kernel: node 0: [mem 0x000000007bc00000-0x000000007bfdffff]
Oct 2 18:46:38.206576 kernel: node 0: [mem 0x000000007bfe0000-0x000000007fffffff]
Oct 2 18:46:38.206597 kernel: node 0: [mem 0x0000000400000000-0x00000004b5ffffff]
Oct 2 18:46:38.206613 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000004b5ffffff]
Oct 2 18:46:38.206628 kernel: On node 0, zone Normal: 8192 pages in unavailable ranges
Oct 2 18:46:38.206643 kernel: psci: probing for conduit method from ACPI.
Oct 2 18:46:38.206658 kernel: psci: PSCIv1.0 detected in firmware.
Oct 2 18:46:38.206677 kernel: psci: Using standard PSCI v0.2 function IDs
Oct 2 18:46:38.206693 kernel: psci: Trusted OS migration not required
Oct 2 18:46:38.206714 kernel: psci: SMC Calling Convention v1.1
Oct 2 18:46:38.206730 kernel: ACPI: SRAT not present
Oct 2 18:46:38.206746 kernel: percpu: Embedded 29 pages/cpu s79960 r8192 d30632 u118784
Oct 2 18:46:38.206766 kernel: pcpu-alloc: s79960 r8192 d30632 u118784 alloc=29*4096
Oct 2 18:46:38.206782 kernel: pcpu-alloc: [0] 0 [0] 1
Oct 2 18:46:38.206797 kernel: Detected PIPT I-cache on CPU0
Oct 2 18:46:38.206812 kernel: CPU features: detected: GIC system register CPU interface
Oct 2 18:46:38.206828 kernel: CPU features: detected: Spectre-v2
Oct 2 18:46:38.206843 kernel: CPU features: detected: Spectre-v3a
Oct 2 18:46:38.206858 kernel: CPU features: detected: Spectre-BHB
Oct 2 18:46:38.206873 kernel: CPU features: kernel page table isolation forced ON by KASLR
Oct 2 18:46:38.206889 kernel: CPU features: detected: Kernel page table isolation (KPTI)
Oct 2 18:46:38.206904 kernel: CPU features: detected: ARM erratum 1742098
Oct 2 18:46:38.206919 kernel: CPU features: detected: ARM errata 1165522, 1319367, or 1530923
Oct 2 18:46:38.206938 kernel: Built 1 zonelists, mobility grouping on. Total pages: 991872
Oct 2 18:46:38.206954 kernel: Policy zone: Normal
Oct 2 18:46:38.206972 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 18:46:38.206989 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space.
Oct 2 18:46:38.207004 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)
Oct 2 18:46:38.207020 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
Oct 2 18:46:38.207035 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off
Oct 2 18:46:38.207050 kernel: software IO TLB: mapped [mem 0x000000007c000000-0x0000000080000000] (64MB)
Oct 2 18:46:38.207067 kernel: Memory: 3826444K/4030464K available (9792K kernel code, 2092K rwdata, 7548K rodata, 34560K init, 779K bss, 204020K reserved, 0K cma-reserved)
Oct 2 18:46:38.207083 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
Oct 2 18:46:38.207102 kernel: trace event string verifier disabled
Oct 2 18:46:38.207117 kernel: rcu: Preemptible hierarchical RCU implementation.
Oct 2 18:46:38.207133 kernel: rcu: RCU event tracing is enabled.
Oct 2 18:46:38.207149 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2.
Oct 2 18:46:38.207165 kernel: Trampoline variant of Tasks RCU enabled.
Oct 2 18:46:38.207181 kernel: Tracing variant of Tasks RCU enabled.
Oct 2 18:46:38.207198 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.
Oct 2 18:46:38.207213 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
Oct 2 18:46:38.207229 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
Oct 2 18:46:38.207245 kernel: GICv3: 96 SPIs implemented
Oct 2 18:46:38.207260 kernel: GICv3: 0 Extended SPIs implemented
Oct 2 18:46:38.207275 kernel: GICv3: Distributor has no Range Selector support
Oct 2 18:46:38.207295 kernel: Root IRQ handler: gic_handle_irq
Oct 2 18:46:38.207311 kernel: GICv3: 16 PPIs implemented
Oct 2 18:46:38.207326 kernel: GICv3: CPU0: found redistributor 0 region 0:0x0000000010200000
Oct 2 18:46:38.207341 kernel: ACPI: SRAT not present
Oct 2 18:46:38.207357 kernel: ITS [mem 0x10080000-0x1009ffff]
Oct 2 18:46:38.207373 kernel: ITS@0x0000000010080000: allocated 8192 Devices @4000a0000 (indirect, esz 8, psz 64K, shr 1)
Oct 2 18:46:38.207389 kernel: ITS@0x0000000010080000: allocated 8192 Interrupt Collections @4000b0000 (flat, esz 8, psz 64K, shr 1)
Oct 2 18:46:38.207405 kernel: GICv3: using LPI property table @0x00000004000c0000
Oct 2 18:46:38.207421 kernel: ITS: Using hypervisor restricted LPI range [128]
Oct 2 18:46:38.207437 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000004000d0000
Oct 2 18:46:38.207453 kernel: arch_timer: cp15 timer(s) running at 83.33MHz (virt).
Oct 2 18:46:38.207473 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x13381ebeec, max_idle_ns: 440795203145 ns
Oct 2 18:46:38.207490 kernel: sched_clock: 56 bits at 83MHz, resolution 12ns, wraps every 4398046511100ns
Oct 2 18:46:38.207509 kernel: Console: colour dummy device 80x25
Oct 2 18:46:38.207526 kernel: printk: console [tty1] enabled
Oct 2 18:46:38.207578 kernel: ACPI: Core revision 20210730
Oct 2 18:46:38.207603 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 166.66 BogoMIPS (lpj=83333)
Oct 2 18:46:38.207621 kernel: pid_max: default: 32768 minimum: 301
Oct 2 18:46:38.207638 kernel: LSM: Security Framework initializing
Oct 2 18:46:38.207654 kernel: SELinux: Initializing.
Oct 2 18:46:38.207671 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
Oct 2 18:46:38.207694 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
Oct 2 18:46:38.207711 kernel: rcu: Hierarchical SRCU implementation.
Oct 2 18:46:38.207727 kernel: Platform MSI: ITS@0x10080000 domain created
Oct 2 18:46:38.207742 kernel: PCI/MSI: ITS@0x10080000 domain created
Oct 2 18:46:38.207758 kernel: Remapping and enabling EFI services.
Oct 2 18:46:38.207774 kernel: smp: Bringing up secondary CPUs ...
Oct 2 18:46:38.207790 kernel: Detected PIPT I-cache on CPU1
Oct 2 18:46:38.207806 kernel: GICv3: CPU1: found redistributor 1 region 0:0x0000000010220000
Oct 2 18:46:38.207822 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000004000e0000
Oct 2 18:46:38.207843 kernel: CPU1: Booted secondary processor 0x0000000001 [0x410fd083]
Oct 2 18:46:38.207859 kernel: smp: Brought up 1 node, 2 CPUs
Oct 2 18:46:38.207875 kernel: SMP: Total of 2 processors activated.
Oct 2 18:46:38.207890 kernel: CPU features: detected: 32-bit EL0 Support
Oct 2 18:46:38.207906 kernel: CPU features: detected: 32-bit EL1 Support
Oct 2 18:46:38.207923 kernel: CPU features: detected: CRC32 instructions
Oct 2 18:46:38.207943 kernel: CPU: All CPU(s) started at EL1
Oct 2 18:46:38.207960 kernel: alternatives: patching kernel code
Oct 2 18:46:38.207977 kernel: devtmpfs: initialized
Oct 2 18:46:38.207997 kernel: KASLR disabled due to lack of seed
Oct 2 18:46:38.208013 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
Oct 2 18:46:38.208030 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear)
Oct 2 18:46:38.208056 kernel: pinctrl core: initialized pinctrl subsystem
Oct 2 18:46:38.208076 kernel: SMBIOS 3.0.0 present.
Oct 2 18:46:38.208093 kernel: DMI: Amazon EC2 a1.large/, BIOS 1.0 11/1/2018
Oct 2 18:46:38.208109 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family
Oct 2 18:46:38.208125 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations
Oct 2 18:46:38.208142 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
Oct 2 18:46:38.208159 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
Oct 2 18:46:38.208175 kernel: audit: initializing netlink subsys (disabled)
Oct 2 18:46:38.208192 kernel: audit: type=2000 audit(0.331:1): state=initialized audit_enabled=0 res=1
Oct 2 18:46:38.208212 kernel: thermal_sys: Registered thermal governor 'step_wise'
Oct 2 18:46:38.208228 kernel: cpuidle: using governor menu
Oct 2 18:46:38.208245 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
Oct 2 18:46:38.208262 kernel: ASID allocator initialised with 32768 entries
Oct 2 18:46:38.208278 kernel: ACPI: bus type PCI registered
Oct 2 18:46:38.208298 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
Oct 2 18:46:38.208315 kernel: Serial: AMBA PL011 UART driver
Oct 2 18:46:38.208331 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages
Oct 2 18:46:38.208348 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages
Oct 2 18:46:38.208364 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
Oct 2 18:46:38.208381 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages
Oct 2 18:46:38.208397 kernel: cryptd: max_cpu_qlen set to 1000
Oct 2 18:46:38.208413 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng)
Oct 2 18:46:38.208430 kernel: ACPI: Added _OSI(Module Device)
Oct 2 18:46:38.208450 kernel: ACPI: Added _OSI(Processor Device)
Oct 2 18:46:38.208467 kernel: ACPI: Added _OSI(3.0 _SCP Extensions)
Oct 2 18:46:38.208484 kernel: ACPI: Added _OSI(Processor Aggregator Device)
Oct 2 18:46:38.208500 kernel: ACPI: Added _OSI(Linux-Dell-Video)
Oct 2 18:46:38.208516 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio)
Oct 2 18:46:38.208533 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics)
Oct 2 18:46:38.208603 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded
Oct 2 18:46:38.208628 kernel: ACPI: Interpreter enabled
Oct 2 18:46:38.208646 kernel: ACPI: Using GIC for interrupt routing
Oct 2 18:46:38.208669 kernel: ACPI: MCFG table detected, 1 entries
Oct 2 18:46:38.208686 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-0f])
Oct 2 18:46:38.209134 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]
Oct 2 18:46:38.209347 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR]
Oct 2 18:46:38.209544 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability]
Oct 2 18:46:38.209776 kernel: acpi PNP0A08:00: ECAM area [mem 0x20000000-0x20ffffff] reserved by PNP0C02:00
Oct 2 18:46:38.209975 kernel: acpi PNP0A08:00: ECAM at [mem 0x20000000-0x20ffffff] for [bus 00-0f]
Oct 2 18:46:38.210006 kernel: ACPI: Remapped I/O 0x000000001fff0000 to [io 0x0000-0xffff window]
Oct 2 18:46:38.210024 kernel: acpiphp: Slot [1] registered
Oct 2 18:46:38.210041 kernel: acpiphp: Slot [2] registered
Oct 2 18:46:38.210058 kernel: acpiphp: Slot [3] registered
Oct 2 18:46:38.210075 kernel: acpiphp: Slot [4] registered
Oct 2 18:46:38.210091 kernel: acpiphp: Slot [5] registered
Oct 2 18:46:38.210108 kernel: acpiphp: Slot [6] registered
Oct 2 18:46:38.210124 kernel: acpiphp: Slot [7] registered
Oct 2 18:46:38.210140 kernel: acpiphp: Slot [8] registered
Oct 2 18:46:38.210161 kernel: acpiphp: Slot [9] registered
Oct 2 18:46:38.210178 kernel: acpiphp: Slot [10] registered
Oct 2 18:46:38.210194 kernel: acpiphp: Slot [11] registered
Oct 2 18:46:38.210211 kernel: acpiphp: Slot [12] registered
Oct 2 18:46:38.210228 kernel: acpiphp: Slot [13] registered
Oct 2 18:46:38.210244 kernel: acpiphp: Slot [14] registered
Oct 2 18:46:38.210260 kernel: acpiphp: Slot [15] registered
Oct 2 18:46:38.210277 kernel: acpiphp: Slot [16] registered
Oct 2 18:46:38.210293 kernel: acpiphp: Slot [17] registered
Oct 2 18:46:38.210309 kernel: acpiphp: Slot [18] registered
Oct 2 18:46:38.210330 kernel: acpiphp: Slot [19] registered
Oct 2 18:46:38.210346 kernel: acpiphp: Slot [20] registered
Oct 2 18:46:38.210362 kernel: acpiphp: Slot [21] registered
Oct 2 18:46:38.210379 kernel: acpiphp: Slot [22] registered
Oct 2 18:46:38.210395 kernel: acpiphp: Slot [23] registered
Oct 2 18:46:38.210412 kernel: acpiphp: Slot [24] registered
Oct 2 18:46:38.210428 kernel: acpiphp: Slot [25] registered
Oct 2 18:46:38.210445 kernel: acpiphp: Slot [26] registered
Oct 2 18:46:38.210461 kernel: acpiphp: Slot [27] registered
Oct 2 18:46:38.210481 kernel: acpiphp: Slot [28] registered
Oct 2 18:46:38.210497 kernel: acpiphp: Slot [29] registered
Oct 2 18:46:38.210513 kernel: acpiphp: Slot [30] registered
Oct 2 18:46:38.210530 kernel: acpiphp: Slot [31] registered
Oct 2 18:46:38.210546 kernel: PCI host bridge to bus 0000:00
Oct 2 18:46:38.210782 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xffffffff window]
Oct 2 18:46:38.210964 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window]
Oct 2 18:46:38.218695 kernel: pci_bus 0000:00: root bus resource [mem 0x400000000000-0x407fffffffff window]
Oct 2 18:46:38.218945 kernel: pci_bus 0000:00: root bus resource [bus 00-0f]
Oct 2 18:46:38.219189 kernel: pci 0000:00:00.0: [1d0f:0200] type 00 class 0x060000
Oct 2 18:46:38.219422 kernel: pci 0000:00:01.0: [1d0f:8250] type 00 class 0x070003
Oct 2 18:46:38.219694 kernel: pci 0000:00:01.0: reg 0x10: [mem 0x80118000-0x80118fff]
Oct 2 18:46:38.219925 kernel: pci 0000:00:04.0: [1d0f:8061] type 00 class 0x010802
Oct 2 18:46:38.220128 kernel: pci 0000:00:04.0: reg 0x10: [mem 0x80114000-0x80117fff]
Oct 2 18:46:38.220337 kernel: pci 0000:00:04.0: PME# supported from D0 D1 D2 D3hot D3cold
Oct 2 18:46:38.220617 kernel: pci 0000:00:05.0: [1d0f:ec20] type 00 class 0x020000
Oct 2 18:46:38.220860 kernel: pci 0000:00:05.0: reg 0x10: [mem 0x80110000-0x80113fff]
Oct 2 18:46:38.221064 kernel: pci 0000:00:05.0: reg 0x18: [mem 0x80000000-0x800fffff pref]
Oct 2 18:46:38.221260 kernel: pci 0000:00:05.0: reg 0x20: [mem 0x80100000-0x8010ffff]
Oct 2 18:46:38.221457 kernel: pci 0000:00:05.0: PME# supported from D0 D1 D2 D3hot D3cold
Oct 2 18:46:38.221681 kernel: pci 0000:00:05.0: BAR 2: assigned [mem 0x80000000-0x800fffff pref]
Oct 2 18:46:38.221891 kernel: pci 0000:00:05.0: BAR 4: assigned [mem 0x80100000-0x8010ffff]
Oct 2 18:46:38.222092 kernel: pci 0000:00:04.0: BAR 0: assigned [mem 0x80110000-0x80113fff]
Oct 2 18:46:38.222314 kernel: pci 0000:00:05.0: BAR 0: assigned [mem 0x80114000-0x80117fff]
Oct 2 18:46:38.226979 kernel: pci 0000:00:01.0: BAR 0: assigned [mem 0x80118000-0x80118fff]
Oct 2 18:46:38.227251 kernel: pci_bus 0000:00: resource 4 [mem 0x80000000-0xffffffff window]
Oct 2 18:46:38.227454 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window]
Oct 2 18:46:38.227716 kernel: pci_bus 0000:00: resource 6 [mem 0x400000000000-0x407fffffffff window]
Oct 2 18:46:38.227755 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35
Oct 2 18:46:38.227774 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36
Oct 2 18:46:38.227791 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37
Oct 2 18:46:38.227808 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38
Oct 2 18:46:38.227825 kernel: iommu: Default domain type: Translated
Oct 2 18:46:38.227842 kernel: iommu: DMA domain TLB invalidation policy: strict mode
Oct 2 18:46:38.227858 kernel: vgaarb: loaded
Oct 2 18:46:38.227874 kernel: pps_core: LinuxPPS API ver. 1 registered
Oct 2 18:46:38.227891 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
Oct 2 18:46:38.227912 kernel: PTP clock support registered
Oct 2 18:46:38.227929 kernel: Registered efivars operations
Oct 2 18:46:38.227945 kernel: clocksource: Switched to clocksource arch_sys_counter
Oct 2 18:46:38.227962 kernel: VFS: Disk quotas dquot_6.6.0
Oct 2 18:46:38.227979 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
Oct 2 18:46:38.227995 kernel: pnp: PnP ACPI init
Oct 2 18:46:38.228205 kernel: system 00:00: [mem 0x20000000-0x2fffffff] could not be reserved
Oct 2 18:46:38.228231 kernel: pnp: PnP ACPI: found 1 devices
Oct 2 18:46:38.228249 kernel: NET: Registered PF_INET protocol family
Oct 2 18:46:38.228271 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)
Oct 2 18:46:38.228288 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)
Oct 2 18:46:38.228305 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
Oct 2 18:46:38.228322 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)
Oct 2 18:46:38.228339 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear)
Oct 2 18:46:38.228356 kernel: TCP: Hash tables configured (established 32768 bind 32768)
Oct 2 18:46:38.228373 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)
Oct 2 18:46:38.228390 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)
Oct 2 18:46:38.228409 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family
Oct 2 18:46:38.228430 kernel: PCI: CLS 0 bytes, default 64
Oct 2 18:46:38.228447 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 3 counters available
Oct 2 18:46:38.228464 kernel: kvm [1]: HYP mode not available
Oct 2 18:46:38.228482 kernel: Initialise system trusted keyrings
Oct 2 18:46:38.228499 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0
Oct 2 18:46:38.228516 kernel: Key type asymmetric registered
Oct 2 18:46:38.228533 kernel: Asymmetric key parser 'x509' registered
Oct 2 18:46:38.233640 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
Oct 2 18:46:38.233688 kernel: io scheduler mq-deadline registered
Oct 2 18:46:38.233721 kernel: io scheduler kyber registered
Oct 2 18:46:38.233740 kernel: io scheduler bfq registered
Oct 2 18:46:38.234048 kernel: pl061_gpio ARMH0061:00: PL061 GPIO chip registered
Oct 2 18:46:38.234086 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0
Oct 2 18:46:38.234104 kernel: ACPI: button: Power Button [PWRB]
Oct 2 18:46:38.234121 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
Oct 2 18:46:38.234138 kernel: ACPI: \_SB_.PCI0.GSI2: Enabled at IRQ 37
Oct 2 18:46:38.234368 kernel: serial 0000:00:01.0: enabling device (0010 -> 0012)
Oct 2 18:46:38.234405 kernel: printk: console [ttyS0] disabled
Oct 2 18:46:38.234424 kernel: 0000:00:01.0: ttyS0 at MMIO 0x80118000 (irq = 14, base_baud = 115200) is a 16550A
Oct 2 18:46:38.234442 kernel: printk: console [ttyS0] enabled
Oct 2 18:46:38.234459 kernel: printk: bootconsole [uart0] disabled
Oct 2 18:46:38.234476 kernel: thunder_xcv, ver 1.0
Oct 2 18:46:38.234492 kernel: thunder_bgx, ver 1.0
Oct 2 18:46:38.234509 kernel: nicpf, ver 1.0
Oct 2 18:46:38.234525 kernel: nicvf, ver 1.0
Oct 2 18:46:38.237242 kernel: rtc-efi rtc-efi.0: registered as rtc0
Oct 2 18:46:38.237475 kernel: rtc-efi rtc-efi.0: setting system clock to 2023-10-02T18:46:37 UTC (1696272397)
Oct 2 18:46:38.237502 kernel: hid: raw HID events driver (C) Jiri Kosina
Oct 2 18:46:38.237520 kernel: NET: Registered PF_INET6 protocol family
Oct 2 18:46:38.237537 kernel: Segment Routing with IPv6
Oct 2 18:46:38.237590 kernel: In-situ OAM (IOAM) with IPv6
Oct 2 18:46:38.237611 kernel: NET: Registered PF_PACKET protocol family
Oct 2 18:46:38.237629 kernel: Key type dns_resolver registered
Oct 2 18:46:38.237646 kernel: registered taskstats version 1
Oct 2 18:46:38.237669 kernel: Loading compiled-in X.509 certificates
Oct 2 18:46:38.237686 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.15.132-flatcar: 3a2a38edc68cb70dc60ec0223a6460557b3bb28d'
Oct 2 18:46:38.237703 kernel: Key type .fscrypt registered
Oct 2 18:46:38.237719 kernel: Key type fscrypt-provisioning registered
Oct 2 18:46:38.237735 kernel: ima: No TPM chip found, activating TPM-bypass!
Oct 2 18:46:38.237752 kernel: ima: Allocated hash algorithm: sha1
Oct 2 18:46:38.237768 kernel: ima: No architecture policies found
Oct 2 18:46:38.237785 kernel: Freeing unused kernel memory: 34560K
Oct 2 18:46:38.237802 kernel: Run /init as init process
Oct 2 18:46:38.237822 kernel: with arguments:
Oct 2 18:46:38.237839 kernel: /init
Oct 2 18:46:38.237855 kernel: with environment:
Oct 2 18:46:38.237871 kernel: HOME=/
Oct 2 18:46:38.237887 kernel: TERM=linux
Oct 2 18:46:38.237903 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a
Oct 2 18:46:38.237925 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 18:46:38.237946 systemd[1]: Detected virtualization amazon.
Oct 2 18:46:38.237969 systemd[1]: Detected architecture arm64.
Oct 2 18:46:38.237987 systemd[1]: Running in initrd.
Oct 2 18:46:38.238005 systemd[1]: No hostname configured, using default hostname.
Oct 2 18:46:38.238022 systemd[1]: Hostname set to <localhost>.
Oct 2 18:46:38.238041 systemd[1]: Initializing machine ID from VM UUID.
Oct 2 18:46:38.238059 systemd[1]: Queued start job for default target initrd.target.
Oct 2 18:46:38.238077 systemd[1]: Started systemd-ask-password-console.path.
Oct 2 18:46:38.238095 systemd[1]: Reached target cryptsetup.target.
Oct 2 18:46:38.238116 systemd[1]: Reached target paths.target.
Oct 2 18:46:38.238134 systemd[1]: Reached target slices.target.
Oct 2 18:46:38.238152 systemd[1]: Reached target swap.target.
Oct 2 18:46:38.238169 systemd[1]: Reached target timers.target.
Oct 2 18:46:38.238188 systemd[1]: Listening on iscsid.socket.
Oct 2 18:46:38.238205 systemd[1]: Listening on iscsiuio.socket.
Oct 2 18:46:38.238223 systemd[1]: Listening on systemd-journald-audit.socket.
Oct 2 18:46:38.238241 systemd[1]: Listening on systemd-journald-dev-log.socket.
Oct 2 18:46:38.238265 systemd[1]: Listening on systemd-journald.socket.
Oct 2 18:46:38.238284 systemd[1]: Listening on systemd-networkd.socket.
Oct 2 18:46:38.238303 systemd[1]: Listening on systemd-udevd-control.socket.
Oct 2 18:46:38.238323 systemd[1]: Listening on systemd-udevd-kernel.socket.
Oct 2 18:46:38.238342 systemd[1]: Reached target sockets.target.
Oct 2 18:46:38.238361 systemd[1]: Starting kmod-static-nodes.service...
Oct 2 18:46:38.238380 systemd[1]: Finished network-cleanup.service.
Oct 2 18:46:38.238399 systemd[1]: Starting systemd-fsck-usr.service...
Oct 2 18:46:38.238418 systemd[1]: Starting systemd-journald.service...
Oct 2 18:46:38.238443 systemd[1]: Starting systemd-modules-load.service...
Oct 2 18:46:38.238462 systemd[1]: Starting systemd-resolved.service...
Oct 2 18:46:38.238481 systemd[1]: Starting systemd-vconsole-setup.service...
Oct 2 18:46:38.238501 systemd[1]: Finished kmod-static-nodes.service.
Oct 2 18:46:38.238520 systemd[1]: Finished systemd-fsck-usr.service.
Oct 2 18:46:38.238540 systemd[1]: Starting systemd-tmpfiles-setup-dev.service...
Oct 2 18:46:38.240623 systemd[1]: Finished systemd-tmpfiles-setup-dev.service.
Oct 2 18:46:38.240659 systemd[1]: Finished systemd-vconsole-setup.service.
Oct 2 18:46:38.240679 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 18:46:38.240707 kernel: audit: type=1130 audit(1696272398.194:2): 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 18:46:38.240726 systemd[1]: Starting dracut-cmdline-ask.service...
Oct 2 18:46:38.240744 kernel: audit: type=1130 audit(1696272398.211:3): 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 18:46:38.240761 kernel: Bridge firewalling registered
Oct 2 18:46:38.240799 systemd-journald[309]: Journal started
Oct 2 18:46:38.240908 systemd-journald[309]: Runtime Journal (/run/log/journal/ec2f44b294607a20d9a4b553620a012b) is 8.0M, max 75.4M, 67.4M free.
Oct 2 18:46:38.194000 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 18:46:38.211000 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 18:46:38.147841 systemd-modules-load[310]: Inserted module 'overlay'
Oct 2 18:46:38.254679 systemd[1]: Started systemd-journald.service.
Oct 2 18:46:38.254731 kernel: audit: type=1130 audit(1696272398.242:4): 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 18:46:38.242000 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 18:46:38.241684 systemd-modules-load[310]: Inserted module 'br_netfilter'
Oct 2 18:46:38.281883 systemd[1]: Finished dracut-cmdline-ask.service.
Oct 2 18:46:38.290731 kernel: SCSI subsystem initialized
Oct 2 18:46:38.287000 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 18:46:38.299398 systemd[1]: Starting dracut-cmdline.service...
Oct 2 18:46:38.306597 kernel: audit: type=1130 audit(1696272398.287: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 18:46:38.317918 systemd-resolved[311]: Positive Trust Anchors:
Oct 2 18:46:38.317948 systemd-resolved[311]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
Oct 2 18:46:38.318004 systemd-resolved[311]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test
Oct 2 18:46:38.347744 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.
Oct 2 18:46:38.347794 kernel: device-mapper: uevent: version 1.0.3
Oct 2 18:46:38.347818 kernel: device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com
Oct 2 18:46:38.353750 systemd-modules-load[310]: Inserted module 'dm_multipath'
Oct 2 18:46:38.361931 systemd[1]: Finished systemd-modules-load.service.
Oct 2 18:46:38.362000 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 18:46:38.374024 systemd[1]: Starting systemd-sysctl.service...
Oct 2 18:46:38.385801 kernel: audit: type=1130 audit(1696272398.362: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 18:46:38.394951 dracut-cmdline[327]: dracut-dracut-053
Oct 2 18:46:38.410442 dracut-cmdline[327]: 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 18:46:38.423238 systemd[1]: Finished systemd-sysctl.service.
Oct 2 18:46:38.434729 kernel: audit: type=1130 audit(1696272398.423: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 18:46:38.423000 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 18:46:38.679587 kernel: Loading iSCSI transport class v2.0-870.
Oct 2 18:46:38.691608 kernel: iscsi: registered transport (tcp)
Oct 2 18:46:38.718405 kernel: iscsi: registered transport (qla4xxx)
Oct 2 18:46:38.718476 kernel: QLogic iSCSI HBA Driver
Oct 2 18:46:38.875622 kernel: random: crng init done
Oct 2 18:46:38.875598 systemd-resolved[311]: Defaulting to hostname 'linux'.
Oct 2 18:46:38.879062 systemd[1]: Started systemd-resolved.service.
Oct 2 18:46:38.894396 kernel: audit: type=1130 audit(1696272398.879: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 18:46:38.879000 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 18:46:38.881030 systemd[1]: Reached target nss-lookup.target.
Oct 2 18:46:38.947635 systemd[1]: Finished dracut-cmdline.service.
Oct 2 18:46:38.949000 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 18:46:38.952532 systemd[1]: Starting dracut-pre-udev.service...
Oct 2 18:46:38.962542 kernel: audit: type=1130 audit(1696272398.949: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 18:46:39.051607 kernel: raid6: neonx8 gen() 6372 MB/s
Oct 2 18:46:39.069589 kernel: raid6: neonx8 xor() 4740 MB/s
Oct 2 18:46:39.087586 kernel: raid6: neonx4 gen() 6486 MB/s
Oct 2 18:46:39.105582 kernel: raid6: neonx4 xor() 4943 MB/s
Oct 2 18:46:39.123583 kernel: raid6: neonx2 gen() 5790 MB/s
Oct 2 18:46:39.141582 kernel: raid6: neonx2 xor() 4539 MB/s
Oct 2 18:46:39.159582 kernel: raid6: neonx1 gen() 4483 MB/s
Oct 2 18:46:39.177582 kernel: raid6: neonx1 xor() 3689 MB/s
Oct 2 18:46:39.195584 kernel: raid6: int64x8 gen() 3408 MB/s
Oct 2 18:46:39.213583 kernel: raid6: int64x8 xor() 2098 MB/s
Oct 2 18:46:39.231580 kernel: raid6: int64x4 gen() 3793 MB/s
Oct 2 18:46:39.249582 kernel: raid6: int64x4 xor() 2200 MB/s
Oct 2 18:46:39.267581 kernel: raid6: int64x2 gen() 3602 MB/s
Oct 2 18:46:39.285581 kernel: raid6: int64x2 xor() 1954 MB/s
Oct 2 18:46:39.303582 kernel: raid6: int64x1 gen() 2775 MB/s
Oct 2 18:46:39.323178 kernel: raid6: int64x1 xor() 1454 MB/s
Oct 2 18:46:39.323210 kernel: raid6: using algorithm neonx4 gen() 6486 MB/s
Oct 2 18:46:39.323233 kernel: raid6: .... xor() 4943 MB/s, rmw enabled
Oct 2 18:46:39.325050 kernel: raid6: using neon recovery algorithm
Oct 2 18:46:39.343588 kernel: xor: measuring software checksum speed
Oct 2 18:46:39.346579 kernel: 8regs : 9336 MB/sec
Oct 2 18:46:39.348581 kernel: 32regs : 11166 MB/sec
Oct 2 18:46:39.353132 kernel: arm64_neon : 9631 MB/sec
Oct 2 18:46:39.353170 kernel: xor: using function: 32regs (11166 MB/sec)
Oct 2 18:46:39.444606 kernel: Btrfs loaded, crc32c=crc32c-generic, zoned=no, fsverity=no
Oct 2 18:46:39.485358 systemd[1]: Finished dracut-pre-udev.service.
Oct 2 18:46:39.485000 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 18:46:39.494000 audit: BPF prog-id=7 op=LOAD
Oct 2 18:46:39.494000 audit: BPF prog-id=8 op=LOAD
Oct 2 18:46:39.496621 kernel: audit: type=1130 audit(1696272399.485: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 18:46:39.497201 systemd[1]: Starting systemd-udevd.service...
Oct 2 18:46:39.536973 systemd-udevd[508]: Using default interface naming scheme 'v252'.
Oct 2 18:46:39.548382 systemd[1]: Started systemd-udevd.service.
Oct 2 18:46:39.549000 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 18:46:39.556019 systemd[1]: Starting dracut-pre-trigger.service...
Oct 2 18:46:39.617891 dracut-pre-trigger[519]: rd.md=0: removing MD RAID activation
Oct 2 18:46:39.739883 systemd[1]: Finished dracut-pre-trigger.service.
Oct 2 18:46:39.740000 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 18:46:39.744458 systemd[1]: Starting systemd-udev-trigger.service...
Oct 2 18:46:39.861412 systemd[1]: Finished systemd-udev-trigger.service.
Oct 2 18:46:39.862000 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 18:46:40.010939 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36
Oct 2 18:46:40.011006 kernel: ena 0000:00:05.0: enabling device (0010 -> 0012)
Oct 2 18:46:40.021691 kernel: ena 0000:00:05.0: ENA device version: 0.10
Oct 2 18:46:40.021993 kernel: ena 0000:00:05.0: ENA controller version: 0.0.1 implementation version 1
Oct 2 18:46:40.037580 kernel: ena 0000:00:05.0: Elastic Network Adapter (ENA) found at mem 80114000, mac addr 06:6c:12:f6:ae:3d
Oct 2 18:46:40.042362 (udev-worker)[571]: Network interface NamePolicy= disabled on kernel command line.
Oct 2 18:46:40.057712 kernel: ACPI: \_SB_.PCI0.GSI0: Enabled at IRQ 35
Oct 2 18:46:40.057790 kernel: nvme nvme0: pci function 0000:00:04.0
Oct 2 18:46:40.067596 kernel: nvme nvme0: 2/0/0 default/read/poll queues
Oct 2 18:46:40.074198 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk.
Oct 2 18:46:40.074267 kernel: GPT:9289727 != 16777215
Oct 2 18:46:40.074291 kernel: GPT:Alternate GPT header not at the end of the disk.
Oct 2 18:46:40.076475 kernel: GPT:9289727 != 16777215
Oct 2 18:46:40.077854 kernel: GPT: Use GNU Parted to correct GPT errors.
Oct 2 18:46:40.081404 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9
Oct 2 18:46:40.177603 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/nvme0n1p6 scanned by (udev-worker) (557)
Oct 2 18:46:40.242395 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device.
Oct 2 18:46:40.370805 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device.
Oct 2 18:46:40.417764 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device.
Oct 2 18:46:40.431108 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device.
Oct 2 18:46:40.437018 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device.
Oct 2 18:46:40.451730 systemd[1]: Starting disk-uuid.service...
Oct 2 18:46:40.474310 disk-uuid[667]: Primary Header is updated.
Oct 2 18:46:40.474310 disk-uuid[667]: Secondary Entries is updated.
Oct 2 18:46:40.474310 disk-uuid[667]: Secondary Header is updated.
Oct 2 18:46:40.483599 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9
Oct 2 18:46:41.503587 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9
Oct 2 18:46:41.505232 disk-uuid[668]: The operation has completed successfully.
Oct 2 18:46:41.800595 systemd[1]: disk-uuid.service: Deactivated successfully.
Oct 2 18:46:41.801246 systemd[1]: Finished disk-uuid.service.
Oct 2 18:46:41.815957 kernel: kauditd_printk_skb: 5 callbacks suppressed
Oct 2 18:46:41.816010 kernel: audit: type=1130 audit(1696272401.803: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 18:46:41.803000 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 18:46:41.814000 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 18:46:41.823698 kernel: audit: type=1131 audit(1696272401.814: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 18:46:41.827916 systemd[1]: Starting verity-setup.service...
Oct 2 18:46:41.879589 kernel: device-mapper: verity: sha256 using implementation "sha256-ce"
Oct 2 18:46:41.979734 systemd[1]: Found device dev-mapper-usr.device.
Oct 2 18:46:41.986503 systemd[1]: Mounting sysusr-usr.mount...
Oct 2 18:46:42.001011 systemd[1]: Finished verity-setup.service.
Oct 2 18:46:42.013018 kernel: audit: type=1130 audit(1696272402.000: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 18:46:42.000000 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 18:46:42.094595 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: norecovery. Quota mode: none.
Oct 2 18:46:42.096314 systemd[1]: Mounted sysusr-usr.mount.
Oct 2 18:46:42.097337 systemd[1]: afterburn-network-kargs.service was skipped because no trigger condition checks were met.
Oct 2 18:46:42.113120 systemd[1]: Starting ignition-setup.service...
Oct 2 18:46:42.118521 systemd[1]: Starting parse-ip-for-networkd.service...
Oct 2 18:46:42.160483 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm
Oct 2 18:46:42.160577 kernel: BTRFS info (device nvme0n1p6): using free space tree
Oct 2 18:46:42.163216 kernel: BTRFS info (device nvme0n1p6): has skinny extents
Oct 2 18:46:42.183611 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations
Oct 2 18:46:42.216246 systemd[1]: mnt-oem.mount: Deactivated successfully.
Oct 2 18:46:42.267769 systemd[1]: Finished ignition-setup.service.
Oct 2 18:46:42.268000 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 18:46:42.283603 kernel: audit: type=1130 audit(1696272402.268: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 18:46:42.287876 systemd[1]: Starting ignition-fetch-offline.service...
Oct 2 18:46:42.507951 systemd[1]: Finished parse-ip-for-networkd.service.
Oct 2 18:46:42.510000 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 18:46:42.517000 audit: BPF prog-id=9 op=LOAD
Oct 2 18:46:42.520633 systemd[1]: Starting systemd-networkd.service...
Oct 2 18:46:42.524350 kernel: audit: type=1130 audit(1696272402.510: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 18:46:42.524390 kernel: audit: type=1334 audit(1696272402.517:21): prog-id=9 op=LOAD
Oct 2 18:46:42.583422 systemd-networkd[1181]: lo: Link UP
Oct 2 18:46:42.583452 systemd-networkd[1181]: lo: Gained carrier
Oct 2 18:46:42.587418 systemd-networkd[1181]: Enumeration completed
Oct 2 18:46:42.606299 kernel: audit: type=1130 audit(1696272402.588: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 18:46:42.588000 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 18:46:42.588013 systemd-networkd[1181]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network.
Oct 2 18:46:42.588177 systemd[1]: Started systemd-networkd.service.
Oct 2 18:46:42.590260 systemd[1]: Reached target network.target.
Oct 2 18:46:42.601514 systemd[1]: Starting iscsiuio.service...
Oct 2 18:46:42.616530 systemd-networkd[1181]: eth0: Link UP
Oct 2 18:46:42.616567 systemd-networkd[1181]: eth0: Gained carrier
Oct 2 18:46:42.633441 systemd[1]: Started iscsiuio.service.
Oct 2 18:46:42.634000 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 18:46:42.644800 systemd-networkd[1181]: eth0: DHCPv4 address 172.31.20.179/20, gateway 172.31.16.1 acquired from 172.31.16.1
Oct 2 18:46:42.649410 kernel: audit: type=1130 audit(1696272402.634: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 18:46:42.651126 systemd[1]: Starting iscsid.service...
Oct 2 18:46:42.664119 iscsid[1186]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi
Oct 2 18:46:42.664119 iscsid[1186]: 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.<reversed domain name>[:identifier].
Oct 2 18:46:42.664119 iscsid[1186]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6.
Oct 2 18:46:42.664119 iscsid[1186]: If using hardware iscsi like qla4xxx this message can be ignored.
Oct 2 18:46:42.664119 iscsid[1186]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi
Oct 2 18:46:42.711445 kernel: audit: type=1130 audit(1696272402.682: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 18:46:42.682000 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 18:46:42.711647 iscsid[1186]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf
Oct 2 18:46:42.681106 systemd[1]: Started iscsid.service.
Oct 2 18:46:42.685270 systemd[1]: Starting dracut-initqueue.service...
Oct 2 18:46:42.736325 systemd[1]: Finished dracut-initqueue.service.
Oct 2 18:46:42.738000 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 18:46:42.740016 systemd[1]: Reached target remote-fs-pre.target.
Oct 2 18:46:42.767078 kernel: audit: type=1130 audit(1696272402.738: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 18:46:42.750127 systemd[1]: Reached target remote-cryptsetup.target.
Oct 2 18:46:42.752104 systemd[1]: Reached target remote-fs.target.
Oct 2 18:46:42.755394 systemd[1]: Starting dracut-pre-mount.service...
Oct 2 18:46:42.795885 systemd[1]: Finished dracut-pre-mount.service.
Oct 2 18:46:42.796000 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 18:46:43.039623 ignition[1105]: Ignition 2.14.0
Oct 2 18:46:43.041475 ignition[1105]: Stage: fetch-offline
Oct 2 18:46:43.043544 ignition[1105]: reading system config file "/usr/lib/ignition/base.d/base.ign"
Oct 2 18:46:43.046296 ignition[1105]: parsing config with SHA512: 6629d8e825d60c9c9d4629d8547ef9a0b839d6b01b7f61a481a1f23308c924b8b0bbf10cae7f7fe3bcaf88b23d1a81baa7771c3670728d4d2a1e665216a1de7b
Oct 2 18:46:43.064481 ignition[1105]: no config dir at "/usr/lib/ignition/base.platform.d/aws"
Oct 2 18:46:43.066914 ignition[1105]: Ignition finished successfully
Oct 2 18:46:43.070113 systemd[1]: Finished ignition-fetch-offline.service.
Oct 2 18:46:43.070000 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 18:46:43.074491 systemd[1]: Starting ignition-fetch.service...
Oct 2 18:46:43.111600 ignition[1205]: Ignition 2.14.0
Oct 2 18:46:43.111634 ignition[1205]: Stage: fetch
Oct 2 18:46:43.112031 ignition[1205]: reading system config file "/usr/lib/ignition/base.d/base.ign"
Oct 2 18:46:43.112096 ignition[1205]: parsing config with SHA512: 6629d8e825d60c9c9d4629d8547ef9a0b839d6b01b7f61a481a1f23308c924b8b0bbf10cae7f7fe3bcaf88b23d1a81baa7771c3670728d4d2a1e665216a1de7b
Oct 2 18:46:43.127012 ignition[1205]: no config dir at "/usr/lib/ignition/base.platform.d/aws"
Oct 2 18:46:43.129698 ignition[1205]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1
Oct 2 18:46:43.158688 ignition[1205]: INFO : PUT result: OK
Oct 2 18:46:43.162987 ignition[1205]: DEBUG : parsed url from cmdline: ""
Oct 2 18:46:43.167283 ignition[1205]: INFO : no config URL provided
Oct 2 18:46:43.167283 ignition[1205]: INFO : reading system config file "/usr/lib/ignition/user.ign"
Oct 2 18:46:43.167283 ignition[1205]: INFO : no config at "/usr/lib/ignition/user.ign"
Oct 2 18:46:43.167283 ignition[1205]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1
Oct 2 18:46:43.177435 ignition[1205]: INFO : PUT result: OK
Oct 2 18:46:43.177435 ignition[1205]: INFO : GET http://169.254.169.254/2019-10-01/user-data: attempt #1
Oct 2 18:46:43.182261 ignition[1205]: INFO : GET result: OK
Oct 2 18:46:43.183992 ignition[1205]: DEBUG : parsing config with SHA512: 489b5d4f52536192d7334931c40d8bf2f8ec70f527c317e830e3d989f0ee69e866ac80e98618bb49f17daab2274ba22e85a1b68219208d9ca549fff094dd50c3
Oct 2 18:46:43.193704 unknown[1205]: fetched base config from "system"
Oct 2 18:46:43.193734 unknown[1205]: fetched base config from "system"
Oct 2 18:46:43.193750 unknown[1205]: fetched user config from "aws"
Oct 2 18:46:43.197628 ignition[1205]: fetch: fetch complete
Oct 2 18:46:43.197645 ignition[1205]: fetch: fetch passed
Oct 2 18:46:43.197762 ignition[1205]: Ignition finished successfully
Oct 2 18:46:43.205868 systemd[1]: Finished ignition-fetch.service.
Oct 2 18:46:43.207000 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 18:46:43.210730 systemd[1]: Starting ignition-kargs.service...
Oct 2 18:46:43.245229 ignition[1211]: Ignition 2.14.0
Oct 2 18:46:43.245258 ignition[1211]: Stage: kargs
Oct 2 18:46:43.245717 ignition[1211]: reading system config file "/usr/lib/ignition/base.d/base.ign"
Oct 2 18:46:43.245783 ignition[1211]: parsing config with SHA512: 6629d8e825d60c9c9d4629d8547ef9a0b839d6b01b7f61a481a1f23308c924b8b0bbf10cae7f7fe3bcaf88b23d1a81baa7771c3670728d4d2a1e665216a1de7b
Oct 2 18:46:43.262842 ignition[1211]: no config dir at "/usr/lib/ignition/base.platform.d/aws"
Oct 2 18:46:43.265352 ignition[1211]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1
Oct 2 18:46:43.269865 ignition[1211]: INFO : PUT result: OK
Oct 2 18:46:43.274788 ignition[1211]: kargs: kargs passed
Oct 2 18:46:43.274905 ignition[1211]: Ignition finished successfully
Oct 2 18:46:43.278623 systemd[1]: Finished ignition-kargs.service.
Oct 2 18:46:43.281000 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 18:46:43.285152 systemd[1]: Starting ignition-disks.service...
Oct 2 18:46:43.316195 ignition[1217]: Ignition 2.14.0
Oct 2 18:46:43.316225 ignition[1217]: Stage: disks
Oct 2 18:46:43.316641 ignition[1217]: reading system config file "/usr/lib/ignition/base.d/base.ign"
Oct 2 18:46:43.316703 ignition[1217]: parsing config with SHA512: 6629d8e825d60c9c9d4629d8547ef9a0b839d6b01b7f61a481a1f23308c924b8b0bbf10cae7f7fe3bcaf88b23d1a81baa7771c3670728d4d2a1e665216a1de7b
Oct 2 18:46:43.334380 ignition[1217]: no config dir at "/usr/lib/ignition/base.platform.d/aws"
Oct 2 18:46:43.336911 ignition[1217]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1
Oct 2 18:46:43.341008 ignition[1217]: INFO : PUT result: OK
Oct 2 18:46:43.345438 ignition[1217]: disks: disks passed
Oct 2 18:46:43.345601 ignition[1217]: Ignition finished successfully
Oct 2 18:46:43.350008 systemd[1]: Finished ignition-disks.service.
Oct 2 18:46:43.351000 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 18:46:43.353460 systemd[1]: Reached target initrd-root-device.target.
Oct 2 18:46:43.354380 systemd[1]: Reached target local-fs-pre.target.
Oct 2 18:46:43.355036 systemd[1]: Reached target local-fs.target.
Oct 2 18:46:43.355369 systemd[1]: Reached target sysinit.target.
Oct 2 18:46:43.357201 systemd[1]: Reached target basic.target.
Oct 2 18:46:43.359864 systemd[1]: Starting systemd-fsck-root.service...
Oct 2 18:46:43.428063 systemd-fsck[1225]: ROOT: clean, 603/553520 files, 56011/553472 blocks
Oct 2 18:46:43.439056 systemd[1]: Finished systemd-fsck-root.service.
Oct 2 18:46:43.441000 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 18:46:43.443929 systemd[1]: Mounting sysroot.mount...
Oct 2 18:46:43.475609 kernel: EXT4-fs (nvme0n1p9): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none.
Oct 2 18:46:43.477616 systemd[1]: Mounted sysroot.mount.
Oct 2 18:46:43.478233 systemd[1]: Reached target initrd-root-fs.target.
Oct 2 18:46:43.491818 systemd[1]: Mounting sysroot-usr.mount...
Oct 2 18:46:43.492714 systemd[1]: flatcar-metadata-hostname.service was skipped because no trigger condition checks were met.
Oct 2 18:46:43.493014 systemd[1]: ignition-remount-sysroot.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot).
Oct 2 18:46:43.493075 systemd[1]: Reached target ignition-diskful.target.
Oct 2 18:46:43.518317 systemd[1]: Mounted sysroot-usr.mount.
Oct 2 18:46:43.524777 systemd[1]: Mounting sysroot-usr-share-oem.mount...
Oct 2 18:46:43.528760 systemd[1]: Starting initrd-setup-root.service...
Oct 2 18:46:43.571586 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/nvme0n1p6 scanned by mount (1242)
Oct 2 18:46:43.571650 initrd-setup-root[1247]: cut: /sysroot/etc/passwd: No such file or directory
Oct 2 18:46:43.582007 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm
Oct 2 18:46:43.582076 kernel: BTRFS info (device nvme0n1p6): using free space tree
Oct 2 18:46:43.585489 kernel: BTRFS info (device nvme0n1p6): has skinny extents
Oct 2 18:46:43.592590 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations
Oct 2 18:46:43.596191 systemd[1]: Mounted sysroot-usr-share-oem.mount.
Oct 2 18:46:43.616858 initrd-setup-root[1273]: cut: /sysroot/etc/group: No such file or directory
Oct 2 18:46:43.634159 initrd-setup-root[1281]: cut: /sysroot/etc/shadow: No such file or directory
Oct 2 18:46:43.656659 initrd-setup-root[1289]: cut: /sysroot/etc/gshadow: No such file or directory
Oct 2 18:46:43.676735 systemd-networkd[1181]: eth0: Gained IPv6LL
Oct 2 18:46:43.889297 systemd[1]: Finished initrd-setup-root.service.
Oct 2 18:46:43.890000 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 18:46:43.893439 systemd[1]: Starting ignition-mount.service...
Oct 2 18:46:43.896368 systemd[1]: Starting sysroot-boot.service...
Oct 2 18:46:43.931103 systemd[1]: sysusr-usr-share-oem.mount: Deactivated successfully.
Oct 2 18:46:43.931385 systemd[1]: sysroot-usr-share-oem.mount: Deactivated successfully.
Oct 2 18:46:43.961616 systemd[1]: Finished sysroot-boot.service.
Oct 2 18:46:43.960000 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 18:46:43.984813 ignition[1310]: INFO : Ignition 2.14.0
Oct 2 18:46:43.984813 ignition[1310]: INFO : Stage: mount
Oct 2 18:46:43.988401 ignition[1310]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign"
Oct 2 18:46:43.988401 ignition[1310]: DEBUG : parsing config with SHA512: 6629d8e825d60c9c9d4629d8547ef9a0b839d6b01b7f61a481a1f23308c924b8b0bbf10cae7f7fe3bcaf88b23d1a81baa7771c3670728d4d2a1e665216a1de7b
Oct 2 18:46:44.007776 ignition[1310]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws"
Oct 2 18:46:44.007776 ignition[1310]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1
Oct 2 18:46:44.013461 ignition[1310]: INFO : PUT result: OK
Oct 2 18:46:44.019606 ignition[1310]: INFO : mount: mount passed
Oct 2 18:46:44.021363 ignition[1310]: INFO : Ignition finished successfully
Oct 2 18:46:44.022682 systemd[1]: Finished ignition-mount.service.
Oct 2 18:46:44.026000 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 18:46:44.029430 systemd[1]: Starting ignition-files.service...
Oct 2 18:46:44.053933 systemd[1]: Mounting sysroot-usr-share-oem.mount...
Oct 2 18:46:44.078595 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/nvme0n1p6 scanned by mount (1317)
Oct 2 18:46:44.084956 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm
Oct 2 18:46:44.085017 kernel: BTRFS info (device nvme0n1p6): using free space tree
Oct 2 18:46:44.085040 kernel: BTRFS info (device nvme0n1p6): has skinny extents
Oct 2 18:46:44.094636 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations
Oct 2 18:46:44.100156 systemd[1]: Mounted sysroot-usr-share-oem.mount.
Oct 2 18:46:44.134838 ignition[1336]: INFO : Ignition 2.14.0
Oct 2 18:46:44.134838 ignition[1336]: INFO : Stage: files
Oct 2 18:46:44.138545 ignition[1336]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign"
Oct 2 18:46:44.138545 ignition[1336]: DEBUG : parsing config with SHA512: 6629d8e825d60c9c9d4629d8547ef9a0b839d6b01b7f61a481a1f23308c924b8b0bbf10cae7f7fe3bcaf88b23d1a81baa7771c3670728d4d2a1e665216a1de7b
Oct 2 18:46:44.156044 ignition[1336]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws"
Oct 2 18:46:44.159063 ignition[1336]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1
Oct 2 18:46:44.163006 ignition[1336]: INFO : PUT result: OK
Oct 2 18:46:44.168221 ignition[1336]: DEBUG : files: compiled without relabeling support, skipping
Oct 2 18:46:44.172914 ignition[1336]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core"
Oct 2 18:46:44.172914 ignition[1336]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core"
Oct 2 18:46:44.231246 ignition[1336]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core"
Oct 2 18:46:44.234449 ignition[1336]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core"
Oct 2 18:46:44.237972 unknown[1336]: wrote ssh authorized keys file for user: core
Oct 2 18:46:44.240964 ignition[1336]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core"
Oct 2 18:46:44.243768 ignition[1336]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/eks/bootstrap.sh"
Oct 2 18:46:44.243768 ignition[1336]: INFO : oem config not found in "/usr/share/oem", looking on oem partition
Oct 2 18:46:44.259139 ignition[1336]: INFO : op(1): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem4121455498"
Oct 2 18:46:44.267744 kernel: BTRFS info: devid 1 device path /dev/nvme0n1p6 changed to /dev/disk/by-label/OEM scanned by ignition (1339)
Oct 2 18:46:44.267785 ignition[1336]: CRITICAL : op(1): [failed] mounting "/dev/disk/by-label/OEM" at "/mnt/oem4121455498": device or resource busy
Oct 2 18:46:44.267785 ignition[1336]: ERROR : failed to mount ext4 device "/dev/disk/by-label/OEM" at "/mnt/oem4121455498", trying btrfs: device or resource busy
Oct 2 18:46:44.267785 ignition[1336]: INFO : op(2): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem4121455498"
Oct 2 18:46:44.280693 ignition[1336]: INFO : op(2): [finished] mounting "/dev/disk/by-label/OEM" at "/mnt/oem4121455498"
Oct 2 18:46:44.283729 ignition[1336]: INFO : op(3): [started] unmounting "/mnt/oem4121455498"
Oct 2 18:46:44.288985 ignition[1336]: INFO : op(3): [finished] unmounting "/mnt/oem4121455498"
Oct 2 18:46:44.288985 ignition[1336]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/eks/bootstrap.sh"
Oct 2 18:46:44.295512 ignition[1336]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/amazon/ssm/amazon-ssm-agent.json"
Oct 2 18:46:44.295512 ignition[1336]: INFO : oem config not found in "/usr/share/oem", looking on oem partition
Oct 2 18:46:44.304450 ignition[1336]: INFO : op(4): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem676032162"
Oct 2 18:46:44.307498 ignition[1336]: CRITICAL : op(4): [failed] mounting "/dev/disk/by-label/OEM" at "/mnt/oem676032162": device or resource busy
Oct 2 18:46:44.307498 ignition[1336]: ERROR : failed to mount ext4 device "/dev/disk/by-label/OEM" at "/mnt/oem676032162", trying btrfs: device or resource busy
Oct 2 18:46:44.307498 ignition[1336]: INFO : op(5): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem676032162"
Oct 2 18:46:44.307498 ignition[1336]: INFO : op(5): [finished] mounting "/dev/disk/by-label/OEM" at "/mnt/oem676032162"
Oct 2 18:46:44.321378 ignition[1336]: INFO : op(6): [started] unmounting "/mnt/oem676032162"
Oct 2 18:46:44.323856 ignition[1336]: INFO : op(6): [finished] unmounting "/mnt/oem676032162"
Oct 2 18:46:44.326289 ignition[1336]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/amazon/ssm/amazon-ssm-agent.json"
Oct 2 18:46:44.326289 ignition[1336]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing file "/sysroot/etc/amazon/ssm/seelog.xml"
Oct 2 18:46:44.344986 ignition[1336]: INFO : oem config not found in "/usr/share/oem", looking on oem partition
Oct 2 18:46:44.355492 ignition[1336]: INFO : op(7): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem3759433130"
Oct 2 18:46:44.359511 ignition[1336]: CRITICAL : op(7): [failed] mounting "/dev/disk/by-label/OEM" at "/mnt/oem3759433130": device or resource busy
Oct 2 18:46:44.359511 ignition[1336]: ERROR : failed to mount ext4 device "/dev/disk/by-label/OEM" at "/mnt/oem3759433130", trying btrfs: device or resource busy
Oct 2 18:46:44.359511 ignition[1336]: INFO : op(8): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem3759433130"
Oct 2 18:46:44.359511 ignition[1336]: INFO : op(8): [finished] mounting "/dev/disk/by-label/OEM" at "/mnt/oem3759433130"
Oct 2 18:46:44.359511 ignition[1336]: INFO : op(9): [started] unmounting "/mnt/oem3759433130"
Oct 2 18:46:44.359511 ignition[1336]: INFO : op(9): [finished] unmounting "/mnt/oem3759433130"
Oct 2 18:46:44.359511 ignition[1336]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing file "/sysroot/etc/amazon/ssm/seelog.xml"
Oct 2 18:46:44.381459 ignition[1336]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/etc/systemd/system/nvidia.service"
Oct 2 18:46:44.381459 ignition[1336]: INFO : oem config not found in "/usr/share/oem", looking on oem partition
Oct 2 18:46:44.399600 ignition[1336]: INFO : op(a): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem1272312849"
Oct 2 18:46:44.399600 ignition[1336]: CRITICAL : op(a): [failed] mounting "/dev/disk/by-label/OEM" at "/mnt/oem1272312849": device or resource busy
Oct 2 18:46:44.399600 ignition[1336]: ERROR : failed to mount ext4 device "/dev/disk/by-label/OEM" at "/mnt/oem1272312849", trying btrfs: device or resource busy
Oct 2 18:46:44.399600 ignition[1336]: INFO : op(b): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem1272312849"
Oct 2 18:46:44.399600 ignition[1336]: INFO : op(b): [finished] mounting "/dev/disk/by-label/OEM" at "/mnt/oem1272312849"
Oct 2 18:46:44.399600 ignition[1336]: INFO : op(c): [started] unmounting "/mnt/oem1272312849"
Oct 2 18:46:44.399600 ignition[1336]: INFO : op(c): [finished] unmounting "/mnt/oem1272312849"
Oct 2 18:46:44.399600 ignition[1336]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/etc/systemd/system/nvidia.service"
Oct 2 18:46:44.399600 ignition[1336]: INFO : files: op(7): [started] processing unit "coreos-metadata-sshkeys@.service"
Oct 2 18:46:44.399600 ignition[1336]: INFO : files: op(7): [finished] processing unit "coreos-metadata-sshkeys@.service"
Oct 2 18:46:44.399600 ignition[1336]: INFO : files: op(8): [started] processing unit "amazon-ssm-agent.service"
Oct 2 18:46:44.399600 ignition[1336]: INFO : files: op(8): op(9): [started] writing unit "amazon-ssm-agent.service" at "/sysroot/etc/systemd/system/amazon-ssm-agent.service"
Oct 2 18:46:44.399600 ignition[1336]: INFO : files: op(8): op(9): [finished] writing unit "amazon-ssm-agent.service" at "/sysroot/etc/systemd/system/amazon-ssm-agent.service"
Oct 2 18:46:44.399600 ignition[1336]: INFO : files: op(8): [finished] processing unit "amazon-ssm-agent.service"
Oct 2 18:46:44.399600 ignition[1336]: INFO : files: op(a): [started] processing unit "nvidia.service"
Oct 2 18:46:44.399600 ignition[1336]: INFO : files: op(a): [finished] processing unit "nvidia.service"
Oct 2 18:46:44.399600 ignition[1336]: INFO : files: op(b): [started] processing unit "etcd-member.service"
Oct 2 18:46:44.399600 ignition[1336]: 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 18:46:44.399600 ignition[1336]: 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 18:46:44.399600 ignition[1336]: INFO : files: op(b): [finished] processing unit "etcd-member.service"
Oct 2 18:46:44.464485 ignition[1336]: INFO : files: op(d): [started] setting preset to enabled for "coreos-metadata-sshkeys@.service "
Oct 2 18:46:44.464485 ignition[1336]: INFO : files: op(d): [finished] setting preset to enabled for "coreos-metadata-sshkeys@.service "
Oct 2 18:46:44.464485 ignition[1336]: INFO : files: op(e): [started] setting preset to enabled for "amazon-ssm-agent.service"
Oct 2 18:46:44.464485 ignition[1336]: INFO : files: op(e): [finished] setting preset to enabled for "amazon-ssm-agent.service"
Oct 2 18:46:44.464485 ignition[1336]: INFO : files: op(f): [started] setting preset to enabled for "nvidia.service"
Oct 2 18:46:44.464485 ignition[1336]: INFO : files: op(f): [finished] setting preset to enabled for "nvidia.service"
Oct 2 18:46:44.464485 ignition[1336]: INFO : files: op(10): [started] setting preset to enabled for "etcd-member.service"
Oct 2 18:46:44.464485 ignition[1336]: INFO : files: op(10): [finished] setting preset to enabled for "etcd-member.service"
Oct 2 18:46:44.512307 ignition[1336]: INFO : files: createResultFile: createFiles: op(11): [started] writing file "/sysroot/etc/.ignition-result.json"
Oct 2 18:46:44.516097 ignition[1336]: INFO : files: createResultFile: createFiles: op(11): [finished] writing file "/sysroot/etc/.ignition-result.json"
Oct 2 18:46:44.516097 ignition[1336]: INFO : files: files passed
Oct 2 18:46:44.516097 ignition[1336]: INFO : Ignition finished successfully
Oct 2 18:46:44.525231 systemd[1]: Finished ignition-files.service.
Oct 2 18:46:44.527000 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 18:46:44.540046 systemd[1]: Starting initrd-setup-root-after-ignition.service...
Oct 2 18:46:44.542104 systemd[1]: torcx-profile-populate.service was skipped because of an unmet condition check (ConditionPathExists=/sysroot/etc/torcx/next-profile).
Oct 2 18:46:44.543507 systemd[1]: Starting ignition-quench.service...
Oct 2 18:46:44.567365 systemd[1]: ignition-quench.service: Deactivated successfully.
Oct 2 18:46:44.568686 systemd[1]: Finished ignition-quench.service.
Oct 2 18:46:44.569000 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 18:46:44.569000 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 18:46:44.589040 initrd-setup-root-after-ignition[1361]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory
Oct 2 18:46:44.594739 systemd[1]: Finished initrd-setup-root-after-ignition.service.
Oct 2 18:46:44.593000 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 18:46:44.595349 systemd[1]: Reached target ignition-complete.target.
Oct 2 18:46:44.600520 systemd[1]: Starting initrd-parse-etc.service...
Oct 2 18:46:44.655446 systemd[1]: initrd-parse-etc.service: Deactivated successfully.
Oct 2 18:46:44.657585 systemd[1]: Finished initrd-parse-etc.service.
Oct 2 18:46:44.662000 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 18:46:44.662000 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 18:46:44.664516 systemd[1]: Reached target initrd-fs.target.
Oct 2 18:46:44.669650 systemd[1]: Reached target initrd.target.
Oct 2 18:46:44.671828 systemd[1]: dracut-mount.service was skipped because no trigger condition checks were met.
Oct 2 18:46:44.674306 systemd[1]: Starting dracut-pre-pivot.service...
Oct 2 18:46:44.728391 systemd[1]: Finished dracut-pre-pivot.service.
Oct 2 18:46:44.730000 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 18:46:44.733412 systemd[1]: Starting initrd-cleanup.service...
Oct 2 18:46:44.763194 systemd[1]: Stopped target nss-lookup.target.
Oct 2 18:46:44.767053 systemd[1]: Stopped target remote-cryptsetup.target.
Oct 2 18:46:44.771217 systemd[1]: Stopped target timers.target.
Oct 2 18:46:44.774848 systemd[1]: dracut-pre-pivot.service: Deactivated successfully.
Oct 2 18:46:44.777276 systemd[1]: Stopped dracut-pre-pivot.service.
Oct 2 18:46:44.779000 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 18:46:44.781141 systemd[1]: Stopped target initrd.target.
Oct 2 18:46:44.784613 systemd[1]: Stopped target basic.target.
Oct 2 18:46:44.788005 systemd[1]: Stopped target ignition-complete.target.
Oct 2 18:46:44.791909 systemd[1]: Stopped target ignition-diskful.target.
Oct 2 18:46:44.795673 systemd[1]: Stopped target initrd-root-device.target.
Oct 2 18:46:44.799586 systemd[1]: Stopped target remote-fs.target.
Oct 2 18:46:44.803155 systemd[1]: Stopped target remote-fs-pre.target.
Oct 2 18:46:44.807036 systemd[1]: Stopped target sysinit.target.
Oct 2 18:46:44.810641 systemd[1]: Stopped target local-fs.target.
Oct 2 18:46:44.814166 systemd[1]: Stopped target local-fs-pre.target.
Oct 2 18:46:44.817853 systemd[1]: Stopped target swap.target.
Oct 2 18:46:44.821098 systemd[1]: dracut-pre-mount.service: Deactivated successfully.
Oct 2 18:46:44.823476 systemd[1]: Stopped dracut-pre-mount.service.
Oct 2 18:46:44.825000 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 18:46:44.827227 systemd[1]: Stopped target cryptsetup.target.
Oct 2 18:46:44.830789 systemd[1]: dracut-initqueue.service: Deactivated successfully.
Oct 2 18:46:44.833134 systemd[1]: Stopped dracut-initqueue.service.
Oct 2 18:46:44.835000 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 18:46:44.836931 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully.
Oct 2 18:46:44.839711 systemd[1]: Stopped initrd-setup-root-after-ignition.service.
Oct 2 18:46:44.842000 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 18:46:44.844027 systemd[1]: ignition-files.service: Deactivated successfully.
Oct 2 18:46:44.844270 systemd[1]: Stopped ignition-files.service.
Oct 2 18:46:44.848000 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 18:46:44.851456 systemd[1]: Stopping ignition-mount.service...
Oct 2 18:46:44.877353 systemd[1]: Stopping iscsid.service...
Oct 2 18:46:44.887056 iscsid[1186]: iscsid shutting down.
Oct 2 18:46:44.885122 systemd[1]: Stopping sysroot-boot.service...
Oct 2 18:46:44.897822 systemd[1]: systemd-udev-trigger.service: Deactivated successfully.
Oct 2 18:46:44.898141 systemd[1]: Stopped systemd-udev-trigger.service.
Oct 2 18:46:44.911462 ignition[1374]: INFO : Ignition 2.14.0
Oct 2 18:46:44.911462 ignition[1374]: INFO : Stage: umount
Oct 2 18:46:44.911462 ignition[1374]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign"
Oct 2 18:46:44.911462 ignition[1374]: DEBUG : parsing config with SHA512: 6629d8e825d60c9c9d4629d8547ef9a0b839d6b01b7f61a481a1f23308c924b8b0bbf10cae7f7fe3bcaf88b23d1a81baa7771c3670728d4d2a1e665216a1de7b
Oct 2 18:46:44.936000 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 18:46:44.938156 systemd[1]: dracut-pre-trigger.service: Deactivated successfully.
Oct 2 18:46:44.942934 systemd[1]: Stopped dracut-pre-trigger.service.
Oct 2 18:46:44.945000 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 18:46:44.953000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 2 18:46:44.951718 systemd[1]: sysroot-boot.mount: Deactivated successfully.
Oct 2 18:46:44.970113 ignition[1374]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws"
Oct 2 18:46:44.970113 ignition[1374]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1
Oct 2 18:46:44.970113 ignition[1374]: INFO : PUT result: OK
Oct 2 18:46:44.970113 ignition[1374]: INFO : umount: umount passed
Oct 2 18:46:44.970113 ignition[1374]: INFO : Ignition finished successfully
Oct 2 18:46:44.977000 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 18:46:44.978000 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 18:46:44.978000 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 18:46:44.982000 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 18:46:44.986000 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 18:46:44.993000 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 18:46:44.998000 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 18:46:45.000000 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 18:46:45.004000 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 18:46:45.016000 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 18:46:44.952948 systemd[1]: iscsid.service: Deactivated successfully.
Oct 2 18:46:45.020000 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 18:46:44.953176 systemd[1]: Stopped iscsid.service.
Oct 2 18:46:44.955889 systemd[1]: Stopping iscsiuio.service...
Oct 2 18:46:44.972582 systemd[1]: iscsiuio.service: Deactivated successfully.
Oct 2 18:46:44.973640 systemd[1]: Stopped iscsiuio.service.
Oct 2 18:46:44.979216 systemd[1]: initrd-cleanup.service: Deactivated successfully.
Oct 2 18:46:44.979453 systemd[1]: Finished initrd-cleanup.service.
Oct 2 18:46:44.980294 systemd[1]: ignition-mount.service: Deactivated successfully.
Oct 2 18:46:44.980477 systemd[1]: Stopped ignition-mount.service.
Oct 2 18:46:44.985077 systemd[1]: sysroot-boot.service: Deactivated successfully.
Oct 2 18:46:44.985276 systemd[1]: Stopped sysroot-boot.service.
Oct 2 18:46:44.988750 systemd[1]: ignition-disks.service: Deactivated successfully.
Oct 2 18:46:44.988852 systemd[1]: Stopped ignition-disks.service.
Oct 2 18:46:44.995011 systemd[1]: ignition-kargs.service: Deactivated successfully.
Oct 2 18:46:45.053000 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 18:46:44.995111 systemd[1]: Stopped ignition-kargs.service.
Oct 2 18:46:45.000329 systemd[1]: ignition-fetch.service: Deactivated successfully.
Oct 2 18:46:45.000434 systemd[1]: Stopped ignition-fetch.service.
Oct 2 18:46:45.061000 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 18:46:45.002246 systemd[1]: Stopped target network.target.
Oct 2 18:46:45.065000 audit: BPF prog-id=6 op=UNLOAD
Oct 2 18:46:45.003933 systemd[1]: ignition-fetch-offline.service: Deactivated successfully.
Oct 2 18:46:45.075000 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 18:46:45.077000 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 18:46:45.004036 systemd[1]: Stopped ignition-fetch-offline.service.
Oct 2 18:46:45.082000 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 18:46:45.005956 systemd[1]: Stopped target paths.target.
Oct 2 18:46:45.007496 systemd[1]: systemd-ask-password-console.path: Deactivated successfully.
Oct 2 18:46:45.007598 systemd[1]: Stopped systemd-ask-password-console.path.
Oct 2 18:46:45.009486 systemd[1]: Stopped target slices.target.
Oct 2 18:46:45.011072 systemd[1]: Stopped target sockets.target.
Oct 2 18:46:45.012843 systemd[1]: iscsid.socket: Deactivated successfully.
Oct 2 18:46:45.012927 systemd[1]: Closed iscsid.socket.
Oct 2 18:46:45.014479 systemd[1]: iscsiuio.socket: Deactivated successfully.
Oct 2 18:46:45.014590 systemd[1]: Closed iscsiuio.socket.
Oct 2 18:46:45.016130 systemd[1]: ignition-setup.service: Deactivated successfully.
Oct 2 18:46:45.016228 systemd[1]: Stopped ignition-setup.service.
Oct 2 18:46:45.018122 systemd[1]: initrd-setup-root.service: Deactivated successfully.
Oct 2 18:46:45.018219 systemd[1]: Stopped initrd-setup-root.service.
Oct 2 18:46:45.022299 systemd[1]: Stopping systemd-networkd.service...
Oct 2 18:46:45.024961 systemd[1]: Stopping systemd-resolved.service...
Oct 2 18:46:45.030636 systemd-networkd[1181]: eth0: DHCPv6 lease lost
Oct 2 18:46:45.131000 audit: BPF prog-id=9 op=UNLOAD
Oct 2 18:46:45.037497 systemd[1]: systemd-networkd.service: Deactivated successfully.
Oct 2 18:46:45.037724 systemd[1]: Stopped systemd-networkd.service.
Oct 2 18:46:45.060040 systemd[1]: systemd-resolved.service: Deactivated successfully.
Oct 2 18:46:45.060251 systemd[1]: Stopped systemd-resolved.service.
Oct 2 18:46:45.066808 systemd[1]: systemd-networkd.socket: Deactivated successfully.
Oct 2 18:46:45.066884 systemd[1]: Closed systemd-networkd.socket.
Oct 2 18:46:45.146000 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 18:46:45.149000 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 18:46:45.149000 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 18:46:45.150000 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 18:46:45.152000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 2 18:46:45.172000 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 18:46:45.069904 systemd[1]: Stopping network-cleanup.service...
Oct 2 18:46:45.071401 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully.
Oct 2 18:46:45.180000 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 18:46:45.188000 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 18:46:45.071505 systemd[1]: Stopped parse-ip-for-networkd.service.
Oct 2 18:46:45.077617 systemd[1]: systemd-sysctl.service: Deactivated successfully.
Oct 2 18:46:45.194000 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 18:46:45.194000 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 18:46:45.077717 systemd[1]: Stopped systemd-sysctl.service.
Oct 2 18:46:45.081728 systemd[1]: systemd-modules-load.service: Deactivated successfully.
Oct 2 18:46:45.081814 systemd[1]: Stopped systemd-modules-load.service.
Oct 2 18:46:45.084826 systemd[1]: Stopping systemd-udevd.service...
Oct 2 18:46:45.111753 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully.
Oct 2 18:46:45.127389 systemd[1]: systemd-udevd.service: Deactivated successfully.
Oct 2 18:46:45.128818 systemd[1]: Stopped systemd-udevd.service.
Oct 2 18:46:45.148168 systemd[1]: systemd-udevd-control.socket: Deactivated successfully.
Oct 2 18:46:45.148279 systemd[1]: Closed systemd-udevd-control.socket.
Oct 2 18:46:45.149811 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully.
Oct 2 18:46:45.149881 systemd[1]: Closed systemd-udevd-kernel.socket.
Oct 2 18:46:45.150841 systemd[1]: dracut-pre-udev.service: Deactivated successfully.
Oct 2 18:46:45.150927 systemd[1]: Stopped dracut-pre-udev.service.
Oct 2 18:46:45.269077 systemd-journald[309]: Received SIGTERM from PID 1 (n/a).
Oct 2 18:46:45.151290 systemd[1]: dracut-cmdline.service: Deactivated successfully.
Oct 2 18:46:45.151363 systemd[1]: Stopped dracut-cmdline.service.
Oct 2 18:46:45.151650 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully.
Oct 2 18:46:45.151721 systemd[1]: Stopped dracut-cmdline-ask.service.
Oct 2 18:46:45.153202 systemd[1]: Starting initrd-udevadm-cleanup-db.service...
Oct 2 18:46:45.153323 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.
Oct 2 18:46:45.153416 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service.
Oct 2 18:46:45.165027 systemd[1]: kmod-static-nodes.service: Deactivated successfully.
Oct 2 18:46:45.165133 systemd[1]: Stopped kmod-static-nodes.service.
Oct 2 18:46:45.173620 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
Oct 2 18:46:45.173720 systemd[1]: Stopped systemd-vconsole-setup.service.
Oct 2 18:46:45.183514 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully.
Oct 2 18:46:45.184457 systemd[1]: network-cleanup.service: Deactivated successfully.
Oct 2 18:46:45.184720 systemd[1]: Stopped network-cleanup.service.
Oct 2 18:46:45.191244 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.
Oct 2 18:46:45.191445 systemd[1]: Finished initrd-udevadm-cleanup-db.service.
Oct 2 18:46:45.197002 systemd[1]: Reached target initrd-switch-root.target.
Oct 2 18:46:45.200878 systemd[1]: Starting initrd-switch-root.service...
Oct 2 18:46:45.227952 systemd[1]: Switching root.
Oct 2 18:46:45.293318 systemd-journald[309]: Journal stopped
Oct 2 18:46:52.502123 kernel: SELinux: Class mctp_socket not defined in policy.
Oct 2 18:46:52.502785 kernel: SELinux: Class anon_inode not defined in policy.
Oct 2 18:46:52.502843 kernel: SELinux: the above unknown classes and permissions will be allowed
Oct 2 18:46:52.502878 kernel: SELinux: policy capability network_peer_controls=1
Oct 2 18:46:52.502917 kernel: SELinux: policy capability open_perms=1
Oct 2 18:46:52.503022 kernel: SELinux: policy capability extended_socket_class=1
Oct 2 18:46:52.503057 kernel: SELinux: policy capability always_check_network=0
Oct 2 18:46:52.503089 kernel: SELinux: policy capability cgroup_seclabel=1
Oct 2 18:46:52.503188 kernel: SELinux: policy capability nnp_nosuid_transition=1
Oct 2 18:46:52.503225 kernel: SELinux: policy capability genfs_seclabel_symlinks=0
Oct 2 18:46:52.503259 kernel: SELinux: policy capability ioctl_skip_cloexec=0
Oct 2 18:46:52.503297 systemd[1]: Successfully loaded SELinux policy in 160.479ms.
Oct 2 18:46:52.503539 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 20.175ms.
Oct 2 18:46:52.524751 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 18:46:52.524797 systemd[1]: Detected virtualization amazon.
Oct 2 18:46:52.524833 systemd[1]: Detected architecture arm64.
Oct 2 18:46:52.524867 systemd[1]: Detected first boot.
Oct 2 18:46:52.524900 systemd[1]: Initializing machine ID from VM UUID.
Oct 2 18:46:52.524931 systemd[1]: Populated /etc with preset unit settings.
Oct 2 18:46:52.524967 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 18:46:52.525010 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 18:46:52.525045 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 18:46:52.525080 kernel: kauditd_printk_skb: 59 callbacks suppressed
Oct 2 18:46:52.525109 kernel: audit: type=1334 audit(1696272411.968:85): prog-id=12 op=LOAD
Oct 2 18:46:52.525141 kernel: audit: type=1334 audit(1696272411.968:86): prog-id=3 op=UNLOAD
Oct 2 18:46:52.525173 kernel: audit: type=1334 audit(1696272411.968:87): prog-id=13 op=LOAD
Oct 2 18:46:52.525205 kernel: audit: type=1334 audit(1696272411.968:88): prog-id=14 op=LOAD
Oct 2 18:46:52.525242 systemd[1]: initrd-switch-root.service: Deactivated successfully.
Oct 2 18:46:52.525273 kernel: audit: type=1334 audit(1696272411.968:89): prog-id=4 op=UNLOAD
Oct 2 18:46:52.525305 kernel: audit: type=1334 audit(1696272411.968:90): prog-id=5 op=UNLOAD
Oct 2 18:46:52.525337 systemd[1]: Stopped initrd-switch-root.service.
Oct 2 18:46:52.525371 kernel: audit: type=1334 audit(1696272411.971:91): prog-id=15 op=LOAD
Oct 2 18:46:52.525402 kernel: audit: type=1334 audit(1696272411.971:92): prog-id=12 op=UNLOAD
Oct 2 18:46:52.525434 kernel: audit: type=1334 audit(1696272411.973:93): prog-id=16 op=LOAD
Oct 2 18:46:52.525466 kernel: audit: type=1334 audit(1696272411.976:94): prog-id=17 op=LOAD
Oct 2 18:46:52.525501 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.
Oct 2 18:46:52.525535 systemd[1]: Created slice system-addon\x2dconfig.slice.
Oct 2 18:46:52.525635 systemd[1]: Created slice system-addon\x2drun.slice.
Oct 2 18:46:52.525675 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice.
Oct 2 18:46:52.525712 systemd[1]: Created slice system-getty.slice.
Oct 2 18:46:52.525746 systemd[1]: Created slice system-modprobe.slice.
Oct 2 18:46:52.525780 systemd[1]: Created slice system-serial\x2dgetty.slice.
Oct 2 18:46:52.525814 systemd[1]: Created slice system-system\x2dcloudinit.slice.
Oct 2 18:46:52.525853 systemd[1]: Created slice system-systemd\x2dfsck.slice.
Oct 2 18:46:52.525884 systemd[1]: Created slice user.slice.
Oct 2 18:46:52.525914 systemd[1]: Started systemd-ask-password-console.path.
Oct 2 18:46:52.525945 systemd[1]: Started systemd-ask-password-wall.path.
Oct 2 18:46:52.525978 systemd[1]: Set up automount boot.automount.
Oct 2 18:46:52.526009 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount.
Oct 2 18:46:52.526042 systemd[1]: Stopped target initrd-switch-root.target.
Oct 2 18:46:52.526072 systemd[1]: Stopped target initrd-fs.target.
Oct 2 18:46:52.526102 systemd[1]: Stopped target initrd-root-fs.target.
Oct 2 18:46:52.526138 systemd[1]: Reached target integritysetup.target.
Oct 2 18:46:52.526171 systemd[1]: Reached target remote-cryptsetup.target.
Oct 2 18:46:52.526205 systemd[1]: Reached target remote-fs.target.
Oct 2 18:46:52.526237 systemd[1]: Reached target slices.target.
Oct 2 18:46:52.526267 systemd[1]: Reached target swap.target.
Oct 2 18:46:52.526297 systemd[1]: Reached target torcx.target.
Oct 2 18:46:52.526329 systemd[1]: Reached target veritysetup.target.
Oct 2 18:46:52.526359 systemd[1]: Listening on systemd-coredump.socket.
Oct 2 18:46:52.526395 systemd[1]: Listening on systemd-initctl.socket.
Oct 2 18:46:52.526434 systemd[1]: Listening on systemd-networkd.socket.
Oct 2 18:46:52.526467 systemd[1]: Listening on systemd-udevd-control.socket.
Oct 2 18:46:52.526498 systemd[1]: Listening on systemd-udevd-kernel.socket.
Oct 2 18:46:52.526528 systemd[1]: Listening on systemd-userdbd.socket.
Oct 2 18:46:52.527306 systemd[1]: Mounting dev-hugepages.mount...
Oct 2 18:46:52.527360 systemd[1]: Mounting dev-mqueue.mount...
Oct 2 18:46:52.527395 systemd[1]: Mounting media.mount...
Oct 2 18:46:52.527427 systemd[1]: Mounting sys-kernel-debug.mount...
Oct 2 18:46:52.527784 systemd[1]: Mounting sys-kernel-tracing.mount...
Oct 2 18:46:52.527818 systemd[1]: Mounting tmp.mount...
Oct 2 18:46:52.527856 systemd[1]: Starting flatcar-tmpfiles.service...
Oct 2 18:46:52.528914 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met.
Oct 2 18:46:52.528995 systemd[1]: Starting kmod-static-nodes.service...
Oct 2 18:46:52.529028 systemd[1]: Starting modprobe@configfs.service...
Oct 2 18:46:52.529059 systemd[1]: Starting modprobe@dm_mod.service...
Oct 2 18:46:52.529092 systemd[1]: Starting modprobe@drm.service...
Oct 2 18:46:52.529124 systemd[1]: Starting modprobe@efi_pstore.service...
Oct 2 18:46:52.529155 systemd[1]: Starting modprobe@fuse.service...
Oct 2 18:46:52.529188 systemd[1]: Starting modprobe@loop.service...
Oct 2 18:46:52.529228 systemd[1]: setup-nsswitch.service was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf).
Oct 2 18:46:52.529262 systemd[1]: systemd-fsck-root.service: Deactivated successfully.
Oct 2 18:46:52.529296 systemd[1]: Stopped systemd-fsck-root.service.
Oct 2 18:46:52.529332 systemd[1]: systemd-fsck-usr.service: Deactivated successfully.
Oct 2 18:46:52.529362 systemd[1]: Stopped systemd-fsck-usr.service.
Oct 2 18:46:52.529393 systemd[1]: Stopped systemd-journald.service.
Oct 2 18:46:52.529428 systemd[1]: Starting systemd-journald.service...
Oct 2 18:46:52.529460 systemd[1]: Starting systemd-modules-load.service...
Oct 2 18:46:52.529492 kernel: loop: module loaded
Oct 2 18:46:52.529532 systemd[1]: Starting systemd-network-generator.service...
Oct 2 18:46:52.529599 systemd[1]: Starting systemd-remount-fs.service...
Oct 2 18:46:52.529634 systemd[1]: Starting systemd-udev-trigger.service...
Oct 2 18:46:52.529671 systemd[1]: verity-setup.service: Deactivated successfully.
Oct 2 18:46:52.529702 systemd[1]: Stopped verity-setup.service.
Oct 2 18:46:52.529733 systemd[1]: Mounted dev-hugepages.mount.
Oct 2 18:46:52.529765 systemd[1]: Mounted dev-mqueue.mount.
Oct 2 18:46:52.529796 systemd[1]: Mounted media.mount.
Oct 2 18:46:52.529826 systemd[1]: Mounted sys-kernel-debug.mount.
Oct 2 18:46:52.529862 systemd[1]: Mounted sys-kernel-tracing.mount.
Oct 2 18:46:52.529892 systemd[1]: Mounted tmp.mount.
Oct 2 18:46:52.529923 systemd[1]: Finished kmod-static-nodes.service.
Oct 2 18:46:52.529953 kernel: fuse: init (API version 7.34)
Oct 2 18:46:52.529982 systemd[1]: modprobe@configfs.service: Deactivated successfully.
Oct 2 18:46:52.530012 systemd[1]: Finished modprobe@configfs.service.
Oct 2 18:46:52.532604 systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
Oct 2 18:46:52.532703 systemd[1]: Finished modprobe@dm_mod.service.
Oct 2 18:46:52.532754 systemd[1]: modprobe@drm.service: Deactivated successfully.
Oct 2 18:46:52.532787 systemd[1]: Finished modprobe@drm.service.
Oct 2 18:46:52.532821 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
Oct 2 18:46:52.532853 systemd[1]: Finished modprobe@efi_pstore.service.
Oct 2 18:46:52.532884 systemd[1]: modprobe@fuse.service: Deactivated successfully.
Oct 2 18:46:52.532917 systemd[1]: Finished modprobe@fuse.service.
Oct 2 18:46:52.532954 systemd[1]: modprobe@loop.service: Deactivated successfully.
Oct 2 18:46:52.532985 systemd[1]: Finished modprobe@loop.service.
Oct 2 18:46:52.533017 systemd[1]: Finished systemd-network-generator.service.
Oct 2 18:46:52.533050 systemd[1]: Finished systemd-remount-fs.service.
Oct 2 18:46:52.533082 systemd[1]: Reached target network-pre.target.
Oct 2 18:46:52.533113 systemd[1]: Mounting sys-fs-fuse-connections.mount...
Oct 2 18:46:52.533144 systemd[1]: Mounting sys-kernel-config.mount...
Oct 2 18:46:52.533182 systemd[1]: remount-root.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).
Oct 2 18:46:52.533218 systemd-journald[1482]: Journal started
Oct 2 18:46:52.533409 systemd-journald[1482]: Runtime Journal (/run/log/journal/ec2f44b294607a20d9a4b553620a012b) is 8.0M, max 75.4M, 67.4M free.
Oct 2 18:46:46.346000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1
Oct 2 18:46:46.633000 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 18:46:46.633000 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 18:46:46.633000 audit: BPF prog-id=10 op=LOAD
Oct 2 18:46:46.633000 audit: BPF prog-id=10 op=UNLOAD
Oct 2 18:46:46.633000 audit: BPF prog-id=11 op=LOAD
Oct 2 18:46:46.633000 audit: BPF prog-id=11 op=UNLOAD
Oct 2 18:46:51.968000 audit: BPF prog-id=12 op=LOAD
Oct 2 18:46:52.539675 systemd[1]: Starting systemd-hwdb-update.service...
Oct 2 18:46:51.968000 audit: BPF prog-id=3 op=UNLOAD
Oct 2 18:46:51.968000 audit: BPF prog-id=13 op=LOAD
Oct 2 18:46:51.968000 audit: BPF prog-id=14 op=LOAD
Oct 2 18:46:51.968000 audit: BPF prog-id=4 op=UNLOAD
Oct 2 18:46:51.968000 audit: BPF prog-id=5 op=UNLOAD
Oct 2 18:46:51.971000 audit: BPF prog-id=15 op=LOAD
Oct 2 18:46:51.971000 audit: BPF prog-id=12 op=UNLOAD
Oct 2 18:46:51.973000 audit: BPF prog-id=16 op=LOAD
Oct 2 18:46:51.976000 audit: BPF prog-id=17 op=LOAD
Oct 2 18:46:51.976000 audit: BPF prog-id=13 op=UNLOAD
Oct 2 18:46:51.976000 audit: BPF prog-id=14 op=UNLOAD
Oct 2 18:46:51.979000 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 18:46:51.990000 audit: BPF prog-id=15 op=UNLOAD
Oct 2 18:46:52.004000 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 18:46:52.004000 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 18:46:52.294000 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 18:46:52.303000 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 18:46:52.548885 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
Oct 2 18:46:52.310000 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 18:46:52.311000 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 18:46:52.312000 audit: BPF prog-id=18 op=LOAD
Oct 2 18:46:52.313000 audit: BPF prog-id=19 op=LOAD
Oct 2 18:46:52.313000 audit: BPF prog-id=20 op=LOAD
Oct 2 18:46:52.313000 audit: BPF prog-id=16 op=UNLOAD
Oct 2 18:46:52.313000 audit: BPF prog-id=17 op=UNLOAD
Oct 2 18:46:52.365000 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 18:46:52.402000 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 18:46:52.422000 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 18:46:52.422000 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 18:46:52.430000 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 18:46:52.430000 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 18:46:52.438000 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 18:46:52.438000 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 18:46:52.447000 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 18:46:52.447000 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 18:46:52.456000 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 18:46:52.456000 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 18:46:52.469000 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 18:46:52.469000 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 18:46:52.477000 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 18:46:52.482000 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 18:46:52.488000 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 18:46:52.488000 audit[1482]: SYSCALL arch=c00000b7 syscall=211 success=yes exit=60 a0=4 a1=ffffea76deb0 a2=4000 a3=1 items=0 ppid=1 pid=1482 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 18:46:52.488000 audit: PROCTITLE proctitle="/usr/lib/systemd/systemd-journald"
Oct 2 18:46:51.966784 systemd[1]: Queued start job for default target multi-user.target.
Oct 2 18:46:52.555058 systemd[1]: Starting systemd-random-seed.service...
Oct 2 18:46:46.954221 /usr/lib/systemd/system-generators/torcx-generator[1408]: time="2023-10-02T18:46:46Z" 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 18:46:52.564954 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met.
Oct 2 18:46:52.565011 systemd[1]: Started systemd-journald.service.
Oct 2 18:46:51.979916 systemd[1]: systemd-journald.service: Deactivated successfully.
Oct 2 18:46:46.956741 /usr/lib/systemd/system-generators/torcx-generator[1408]: time="2023-10-02T18:46:46Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json
Oct 2 18:46:46.956806 /usr/lib/systemd/system-generators/torcx-generator[1408]: time="2023-10-02T18:46:46Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json
Oct 2 18:46:46.956878 /usr/lib/systemd/system-generators/torcx-generator[1408]: time="2023-10-02T18:46:46Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12"
Oct 2 18:46:46.956904 /usr/lib/systemd/system-generators/torcx-generator[1408]: time="2023-10-02T18:46:46Z" level=debug msg="skipped missing lower profile" missing profile=oem
Oct 2 18:46:46.956973 /usr/lib/systemd/system-generators/torcx-generator[1408]: time="2023-10-02T18:46:46Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory"
Oct 2 18:46:46.957005 /usr/lib/systemd/system-generators/torcx-generator[1408]: time="2023-10-02T18:46:46Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)=
Oct 2 18:46:52.567000 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 18:46:52.570000 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 18:46:46.957479 /usr/lib/systemd/system-generators/torcx-generator[1408]: time="2023-10-02T18:46:46Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack
Oct 2 18:46:52.569603 systemd[1]: Finished systemd-modules-load.service.
Oct 2 18:46:46.957641 /usr/lib/systemd/system-generators/torcx-generator[1408]: time="2023-10-02T18:46:46Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json
Oct 2 18:46:52.571957 systemd[1]: Mounted sys-fs-fuse-connections.mount.
Oct 2 18:46:46.957751 /usr/lib/systemd/system-generators/torcx-generator[1408]: time="2023-10-02T18:46:46Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json
Oct 2 18:46:52.574244 systemd[1]: Mounted sys-kernel-config.mount.
Oct 2 18:46:46.958807 /usr/lib/systemd/system-generators/torcx-generator[1408]: time="2023-10-02T18:46:46Z" 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 18:46:52.578819 systemd[1]: Starting systemd-journal-flush.service...
Oct 2 18:46:46.958892 /usr/lib/systemd/system-generators/torcx-generator[1408]: time="2023-10-02T18:46:46Z" 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 18:46:52.583077 systemd[1]: Starting systemd-sysctl.service...
Oct 2 18:46:46.958943 /usr/lib/systemd/system-generators/torcx-generator[1408]: time="2023-10-02T18:46:46Z" 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 18:46:46.958984 /usr/lib/systemd/system-generators/torcx-generator[1408]: time="2023-10-02T18:46:46Z" 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 18:46:46.959075 /usr/lib/systemd/system-generators/torcx-generator[1408]: time="2023-10-02T18:46:46Z" 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 18:46:46.959115 /usr/lib/systemd/system-generators/torcx-generator[1408]: time="2023-10-02T18:46:46Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store
Oct 2 18:46:51.057585 /usr/lib/systemd/system-generators/torcx-generator[1408]: time="2023-10-02T18:46:51Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl
Oct 2 18:46:51.058123 /usr/lib/systemd/system-generators/torcx-generator[1408]: time="2023-10-02T18:46:51Z" 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 18:46:51.058409 /usr/lib/systemd/system-generators/torcx-generator[1408]: time="2023-10-02T18:46:51Z" 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 18:46:51.058901 /usr/lib/systemd/system-generators/torcx-generator[1408]: time="2023-10-02T18:46:51Z" 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 18:46:51.059015 /usr/lib/systemd/system-generators/torcx-generator[1408]: time="2023-10-02T18:46:51Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile=
Oct 2 18:46:51.059155 /usr/lib/systemd/system-generators/torcx-generator[1408]: time="2023-10-02T18:46:51Z" 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 18:46:52.632360 systemd[1]: Finished systemd-random-seed.service.
Oct 2 18:46:52.632000 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 18:46:52.634762 systemd[1]: Reached target first-boot-complete.target.
Oct 2 18:46:52.640156 systemd-journald[1482]: Time spent on flushing to /var/log/journal/ec2f44b294607a20d9a4b553620a012b is 84.872ms for 1101 entries.
Oct 2 18:46:52.640156 systemd-journald[1482]: System Journal (/var/log/journal/ec2f44b294607a20d9a4b553620a012b) is 8.0M, max 195.6M, 187.6M free.
Oct 2 18:46:52.781776 systemd-journald[1482]: Received client request to flush runtime journal.
Oct 2 18:46:52.734000 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 18:46:52.734321 systemd[1]: Finished systemd-sysctl.service.
Oct 2 18:46:52.784499 systemd[1]: Finished systemd-journal-flush.service.
Oct 2 18:46:52.785000 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 18:46:52.796446 systemd[1]: Finished systemd-udev-trigger.service.
Oct 2 18:46:52.797000 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 18:46:52.801086 systemd[1]: Starting systemd-udev-settle.service...
Oct 2 18:46:52.831413 systemd[1]: Finished flatcar-tmpfiles.service.
Oct 2 18:46:52.831000 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 18:46:52.835895 systemd[1]: Starting systemd-sysusers.service...
Oct 2 18:46:52.847651 udevadm[1520]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in.
Oct 2 18:46:52.961293 systemd[1]: Finished systemd-sysusers.service.
Oct 2 18:46:52.962000 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 18:46:52.965750 systemd[1]: Starting systemd-tmpfiles-setup-dev.service...
Oct 2 18:46:53.238670 systemd[1]: Finished systemd-tmpfiles-setup-dev.service.
Oct 2 18:46:53.239000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 2 18:46:53.791096 systemd[1]: Finished systemd-hwdb-update.service.
Oct 2 18:46:53.792000 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 18:46:53.793000 audit: BPF prog-id=21 op=LOAD
Oct 2 18:46:53.793000 audit: BPF prog-id=22 op=LOAD
Oct 2 18:46:53.793000 audit: BPF prog-id=7 op=UNLOAD
Oct 2 18:46:53.793000 audit: BPF prog-id=8 op=UNLOAD
Oct 2 18:46:53.797626 systemd[1]: Starting systemd-udevd.service...
Oct 2 18:46:53.848341 systemd-udevd[1529]: Using default interface naming scheme 'v252'.
Oct 2 18:46:53.895199 systemd[1]: Started systemd-udevd.service.
Oct 2 18:46:53.895000 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 18:46:53.897000 audit: BPF prog-id=23 op=LOAD
Oct 2 18:46:53.902911 systemd[1]: Starting systemd-networkd.service...
Oct 2 18:46:53.919000 audit: BPF prog-id=24 op=LOAD
Oct 2 18:46:53.919000 audit: BPF prog-id=25 op=LOAD
Oct 2 18:46:53.919000 audit: BPF prog-id=26 op=LOAD
Oct 2 18:46:53.922581 systemd[1]: Starting systemd-userdbd.service...
Oct 2 18:46:54.063852 systemd[1]: Started systemd-userdbd.service.
Oct 2 18:46:54.064000 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 18:46:54.069212 systemd[1]: Condition check resulted in dev-ttyS0.device being skipped.
Oct 2 18:46:54.112981 (udev-worker)[1534]: Network interface NamePolicy= disabled on kernel command line.
Oct 2 18:46:54.291143 systemd-networkd[1535]: lo: Link UP
Oct 2 18:46:54.291169 systemd-networkd[1535]: lo: Gained carrier
Oct 2 18:46:54.292100 systemd-networkd[1535]: Enumeration completed
Oct 2 18:46:54.292278 systemd[1]: Started systemd-networkd.service.
Oct 2 18:46:54.292372 systemd-networkd[1535]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network.
Oct 2 18:46:54.295000 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 18:46:54.298365 systemd[1]: Starting systemd-networkd-wait-online.service...
Oct 2 18:46:54.308610 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
Oct 2 18:46:54.309092 systemd-networkd[1535]: eth0: Link UP
Oct 2 18:46:54.309386 systemd-networkd[1535]: eth0: Gained carrier
Oct 2 18:46:54.339839 systemd-networkd[1535]: eth0: DHCPv4 address 172.31.20.179/20, gateway 172.31.16.1 acquired from 172.31.16.1
Oct 2 18:46:54.426633 kernel: BTRFS info: devid 1 device path /dev/disk/by-label/OEM changed to /dev/nvme0n1p6 scanned by (udev-worker) (1541)
Oct 2 18:46:54.654722 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device.
Oct 2 18:46:54.659419 systemd[1]: Finished systemd-udev-settle.service.
Oct 2 18:46:54.660000 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 18:46:54.664289 systemd[1]: Starting lvm2-activation-early.service...
Oct 2 18:46:54.726568 lvm[1648]: WARNING: Failed to connect to lvmetad. Falling back to device scanning.
Oct 2 18:46:54.766697 systemd[1]: Finished lvm2-activation-early.service.
Oct 2 18:46:54.768985 systemd[1]: Reached target cryptsetup.target.
Oct 2 18:46:54.767000 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 18:46:54.773174 systemd[1]: Starting lvm2-activation.service...
Oct 2 18:46:54.788798 lvm[1649]: WARNING: Failed to connect to lvmetad. Falling back to device scanning.
Oct 2 18:46:54.826992 systemd[1]: Finished lvm2-activation.service.
Oct 2 18:46:54.827000 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 18:46:54.829663 systemd[1]: Reached target local-fs-pre.target.
Oct 2 18:46:54.831716 systemd[1]: var-lib-machines.mount was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw).
Oct 2 18:46:54.831763 systemd[1]: Reached target local-fs.target.
Oct 2 18:46:54.833715 systemd[1]: Reached target machines.target.
Oct 2 18:46:54.838481 systemd[1]: Starting ldconfig.service...
Oct 2 18:46:54.840983 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met.
Oct 2 18:46:54.841123 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 18:46:54.843948 systemd[1]: Starting systemd-boot-update.service...
Oct 2 18:46:54.848287 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service...
Oct 2 18:46:54.853241 systemd[1]: Starting systemd-machine-id-commit.service...
Oct 2 18:46:54.855483 systemd[1]: systemd-sysext.service was skipped because no trigger condition checks were met.
Oct 2 18:46:54.855648 systemd[1]: ensure-sysext.service was skipped because no trigger condition checks were met.
Oct 2 18:46:54.858871 systemd[1]: Starting systemd-tmpfiles-setup.service...
Oct 2 18:46:54.902578 systemd[1]: boot.automount: Got automount request for /boot, triggered by 1651 (bootctl)
Oct 2 18:46:54.906015 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service...
Oct 2 18:46:54.931163 systemd-tmpfiles[1654]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring.
Oct 2 18:46:54.936158 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
Oct 2 18:46:54.936397 systemd-tmpfiles[1654]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring.
Oct 2 18:46:54.939515 systemd-tmpfiles[1654]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring.
Oct 2 18:46:54.941000 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 18:46:54.940851 systemd[1]: Finished systemd-machine-id-commit.service.
Oct 2 18:46:54.949000 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 18:46:54.948917 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service.
Oct 2 18:46:55.030646 systemd-fsck[1659]: fsck.fat 4.2 (2021-01-31)
Oct 2 18:46:55.030646 systemd-fsck[1659]: /dev/nvme0n1p1: 236 files, 113463/258078 clusters
Oct 2 18:46:55.036009 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service.
Oct 2 18:46:55.037000 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 18:46:55.041752 systemd[1]: Mounting boot.mount...
Oct 2 18:46:55.086434 systemd[1]: Mounted boot.mount.
Oct 2 18:46:55.115979 systemd[1]: Finished systemd-boot-update.service.
Oct 2 18:46:55.117000 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 18:46:55.299880 systemd[1]: Finished systemd-tmpfiles-setup.service.
Oct 2 18:46:55.300000 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 18:46:55.304793 systemd[1]: Starting audit-rules.service...
Oct 2 18:46:55.315000 audit: BPF prog-id=27 op=LOAD
Oct 2 18:46:55.308743 systemd[1]: Starting clean-ca-certificates.service...
Oct 2 18:46:55.313338 systemd[1]: Starting systemd-journal-catalog-update.service...
Oct 2 18:46:55.322000 audit: BPF prog-id=28 op=LOAD
Oct 2 18:46:55.320714 systemd[1]: Starting systemd-resolved.service...
Oct 2 18:46:55.324752 systemd-networkd[1535]: eth0: Gained IPv6LL
Oct 2 18:46:55.338000 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 18:46:55.328468 systemd[1]: Starting systemd-timesyncd.service...
Oct 2 18:46:55.333139 systemd[1]: Starting systemd-update-utmp.service...
Oct 2 18:46:55.337246 systemd[1]: Finished systemd-networkd-wait-online.service.
Oct 2 18:46:55.363019 systemd[1]: Finished clean-ca-certificates.service.
Oct 2 18:46:55.363000 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 18:46:55.365195 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt).
Oct 2 18:46:55.411000 audit[1678]: SYSTEM_BOOT pid=1678 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 18:46:55.430891 systemd[1]: Finished systemd-update-utmp.service.
Oct 2 18:46:55.431000 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 18:46:55.472293 systemd[1]: Finished systemd-journal-catalog-update.service.
Oct 2 18:46:55.473000 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 18:46:55.536142 systemd[1]: Started systemd-timesyncd.service.
Oct 2 18:46:55.537000 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 18:46:55.538416 systemd[1]: Reached target time-set.target.
Oct 2 18:46:55.578164 systemd-resolved[1676]: Positive Trust Anchors:
Oct 2 18:46:55.578199 systemd-resolved[1676]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
Oct 2 18:46:55.578252 systemd-resolved[1676]: 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 18:46:55.606136 systemd-resolved[1676]: Defaulting to hostname 'linux'.
Oct 2 18:46:55.610541 systemd[1]: Started systemd-resolved.service.
Oct 2 18:46:55.611000 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 18:46:55.612631 systemd[1]: Reached target network.target.
Oct 2 18:46:55.614660 systemd[1]: Reached target network-online.target.
Oct 2 18:46:55.616666 systemd[1]: Reached target nss-lookup.target.
Oct 2 18:46:55.629000 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 18:46:55.629000 audit[1694]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffff070720 a2=420 a3=0 items=0 ppid=1673 pid=1694 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 18:46:55.629000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573
Oct 2 18:46:55.631772 augenrules[1694]: No rules
Oct 2 18:46:55.633687 systemd[1]: Finished audit-rules.service.
Oct 2 18:46:55.695342 systemd-timesyncd[1677]: Contacted time server 167.172.145.185:123 (0.flatcar.pool.ntp.org).
Oct 2 18:46:55.695474 systemd-timesyncd[1677]: Initial clock synchronization to Mon 2023-10-02 18:46:55.733211 UTC.
Oct 2 18:46:56.209888 ldconfig[1650]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start.
Oct 2 18:46:56.219269 systemd[1]: Finished ldconfig.service.
Oct 2 18:46:56.224203 systemd[1]: Starting systemd-update-done.service...
Oct 2 18:46:56.247220 systemd[1]: Finished systemd-update-done.service.
Oct 2 18:46:56.249944 systemd[1]: Reached target sysinit.target.
Oct 2 18:46:56.251857 systemd[1]: Started motdgen.path.
Oct 2 18:46:56.253634 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path.
Oct 2 18:46:56.256285 systemd[1]: Started logrotate.timer.
Oct 2 18:46:56.258169 systemd[1]: Started mdadm.timer.
Oct 2 18:46:56.259789 systemd[1]: Started systemd-tmpfiles-clean.timer.
Oct 2 18:46:56.261698 systemd[1]: update-engine-stub.timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate).
Oct 2 18:46:56.261747 systemd[1]: Reached target paths.target.
Oct 2 18:46:56.263672 systemd[1]: Reached target timers.target.
Oct 2 18:46:56.265929 systemd[1]: Listening on dbus.socket.
Oct 2 18:46:56.269891 systemd[1]: Starting docker.socket...
Oct 2 18:46:56.279363 systemd[1]: Listening on sshd.socket.
Oct 2 18:46:56.281303 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 18:46:56.282320 systemd[1]: Listening on docker.socket.
Oct 2 18:46:56.284397 systemd[1]: Reached target sockets.target.
Oct 2 18:46:56.286264 systemd[1]: Reached target basic.target.
Oct 2 18:46:56.288127 systemd[1]: addon-config@usr-share-oem.service was skipped because no trigger condition checks were met.
Oct 2 18:46:56.288202 systemd[1]: addon-run@usr-share-oem.service was skipped because no trigger condition checks were met.
Oct 2 18:46:56.291215 systemd[1]: Started amazon-ssm-agent.service.
Oct 2 18:46:56.296764 systemd[1]: Starting containerd.service...
Oct 2 18:46:56.301038 systemd[1]: Starting coreos-metadata-sshkeys@core.service...
Oct 2 18:46:56.307258 systemd[1]: Starting coreos-metadata.service...
Oct 2 18:46:56.312541 systemd[1]: Starting dbus.service...
Oct 2 18:46:56.316486 systemd[1]: Starting enable-oem-cloudinit.service...
Oct 2 18:46:56.323885 systemd[1]: Starting extend-filesystems.service...
Oct 2 18:46:56.325821 systemd[1]: flatcar-setup-environment.service was skipped because of an unmet condition check (ConditionPathExists=/usr/share/oem/bin/flatcar-setup-environment).
Oct 2 18:46:56.328291 systemd[1]: Starting motdgen.service...
Oct 2 18:46:56.332225 systemd[1]: Started nvidia.service.
Oct 2 18:46:56.336782 systemd[1]: Starting ssh-key-proc-cmdline.service...
Oct 2 18:46:56.342478 systemd[1]: Starting sshd-keygen.service...
Oct 2 18:46:56.447256 jq[1715]: true
Oct 2 18:46:56.354372 systemd[1]: Starting systemd-logind.service...
Oct 2 18:46:56.356086 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 18:46:56.356237 systemd[1]: tcsd.service was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0).
Oct 2 18:46:56.357193 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details.
Oct 2 18:46:56.490811 jq[1707]: false
Oct 2 18:46:56.358912 systemd[1]: Starting update-engine.service...
Oct 2 18:46:56.364766 systemd[1]: Starting update-ssh-keys-after-ignition.service...
Oct 2 18:46:56.472234 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully.
Oct 2 18:46:56.472591 systemd[1]: Finished ssh-key-proc-cmdline.service.
Oct 2 18:46:56.500179 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'.
Oct 2 18:46:56.500613 systemd[1]: Condition check resulted in enable-oem-cloudinit.service being skipped.
Oct 2 18:46:56.534444 jq[1720]: true
Oct 2 18:46:56.612335 dbus-daemon[1706]: [system] SELinux support is enabled
Oct 2 18:46:56.613224 systemd[1]: Started dbus.service.
Oct 2 18:46:56.618473 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 18:46:56.618521 systemd[1]: Reached target system-config.target.
Oct 2 18:46:56.620613 systemd[1]: user-cloudinit-proc-cmdline.service was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url).
Oct 2 18:46:56.620659 systemd[1]: Reached target user-config.target.
Oct 2 18:46:56.661413 extend-filesystems[1708]: Found nvme0n1
Oct 2 18:46:56.661413 extend-filesystems[1708]: Found nvme0n1p4
Oct 2 18:46:56.665472 extend-filesystems[1708]: Found nvme0n1p6
Oct 2 18:46:56.665472 extend-filesystems[1708]: Found nvme0n1p7
Oct 2 18:46:56.665472 extend-filesystems[1708]: Found nvme0n1p1
Oct 2 18:46:56.665472 extend-filesystems[1708]: Found nvme0n1p2
Oct 2 18:46:56.665472 extend-filesystems[1708]: Found nvme0n1p3
Oct 2 18:46:56.665472 extend-filesystems[1708]: Found usr
Oct 2 18:46:56.665472 extend-filesystems[1708]: Found nvme0n1p9
Oct 2 18:46:56.665472 extend-filesystems[1708]: Checking size of /dev/nvme0n1p9
Oct 2 18:46:56.712225 dbus-daemon[1706]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.0' (uid=244 pid=1535 comm="/usr/lib/systemd/systemd-networkd" label="system_u:system_r:kernel_t:s0")
Oct 2 18:46:56.721905 systemd[1]: Starting systemd-hostnamed.service...
Oct 2 18:46:56.850832 extend-filesystems[1708]: Resized partition /dev/nvme0n1p9
Oct 2 18:46:56.875628 systemd[1]: motdgen.service: Deactivated successfully.
Oct 2 18:46:56.878301 extend-filesystems[1760]: resize2fs 1.46.5 (30-Dec-2021)
Oct 2 18:46:56.876008 systemd[1]: Finished motdgen.service.
Oct 2 18:46:56.914601 kernel: EXT4-fs (nvme0n1p9): resizing filesystem from 553472 to 1489915 blocks
Oct 2 18:46:56.941659 update_engine[1714]: I1002 18:46:56.939226 1714 main.cc:92] Flatcar Update Engine starting
Oct 2 18:46:56.944079 systemd-logind[1713]: Watching system buttons on /dev/input/event0 (Power Button)
Oct 2 18:46:56.952652 systemd[1]: Started update-engine.service.
Oct 2 18:46:56.957954 systemd[1]: Started locksmithd.service.
Oct 2 18:46:56.960826 update_engine[1714]: I1002 18:46:56.960780 1714 update_check_scheduler.cc:74] Next update check in 4m21s
Oct 2 18:46:56.966414 systemd-logind[1713]: New seat seat0.
Oct 2 18:46:56.971621 kernel: EXT4-fs (nvme0n1p9): resized filesystem to 1489915
Oct 2 18:46:56.987269 systemd[1]: Started systemd-logind.service.
Oct 2 18:46:57.055743 amazon-ssm-agent[1702]: 2023/10/02 18:46:57 Failed to load instance info from vault. RegistrationKey does not exist.
Oct 2 18:46:57.058338 extend-filesystems[1760]: Filesystem at /dev/nvme0n1p9 is mounted on /; on-line resizing required
Oct 2 18:46:57.058338 extend-filesystems[1760]: old_desc_blocks = 1, new_desc_blocks = 1
Oct 2 18:46:57.058338 extend-filesystems[1760]: The filesystem on /dev/nvme0n1p9 is now 1489915 (4k) blocks long.
Oct 2 18:46:57.084285 extend-filesystems[1708]: Resized filesystem in /dev/nvme0n1p9
Oct 2 18:46:57.086534 bash[1779]: Updated "/home/core/.ssh/authorized_keys"
Oct 2 18:46:57.062137 systemd[1]: extend-filesystems.service: Deactivated successfully.
Oct 2 18:46:57.062585 systemd[1]: Finished extend-filesystems.service.
Oct 2 18:46:57.088106 systemd[1]: Finished update-ssh-keys-after-ignition.service.
Oct 2 18:46:57.094382 amazon-ssm-agent[1702]: Initializing new seelog logger
Oct 2 18:46:57.094718 amazon-ssm-agent[1702]: New Seelog Logger Creation Complete
Oct 2 18:46:57.097544 amazon-ssm-agent[1702]: 2023/10/02 18:46:57 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json.
Oct 2 18:46:57.097544 amazon-ssm-agent[1702]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json.
Oct 2 18:46:57.098126 amazon-ssm-agent[1702]: 2023/10/02 18:46:57 processing appconfig overrides
Oct 2 18:46:57.132278 env[1730]: time="2023-10-02T18:46:57.132174236Z" level=info msg="starting containerd" revision=92b3a9d6f1b3bcc6dc74875cfdea653fe39f09c2 version=1.6.16
Oct 2 18:46:57.263885 env[1730]: time="2023-10-02T18:46:57.263625997Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Oct 2 18:46:57.271090 env[1730]: time="2023-10-02T18:46:57.270972431Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Oct 2 18:46:57.289592 env[1730]: time="2023-10-02T18:46:57.287844907Z" 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 18:46:57.289592 env[1730]: time="2023-10-02T18:46:57.287919594Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
Oct 2 18:46:57.289592 env[1730]: time="2023-10-02T18:46:57.288342779Z" 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 18:46:57.289592 env[1730]: time="2023-10-02T18:46:57.288395733Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
Oct 2 18:46:57.289592 env[1730]: time="2023-10-02T18:46:57.288430406Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured"
Oct 2 18:46:57.289592 env[1730]: time="2023-10-02T18:46:57.288456275Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Oct 2 18:46:57.289592 env[1730]: time="2023-10-02T18:46:57.288717051Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Oct 2 18:46:57.289592 env[1730]: time="2023-10-02T18:46:57.289466090Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
Oct 2 18:46:57.291973 env[1730]: time="2023-10-02T18:46:57.291904490Z" 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 18:46:57.295706 env[1730]: time="2023-10-02T18:46:57.295642236Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Oct 2 18:46:57.298829 env[1730]: time="2023-10-02T18:46:57.298769252Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured"
Oct 2 18:46:57.304960 env[1730]: time="2023-10-02T18:46:57.304824410Z" level=info msg="metadata content store policy set" policy=shared
Oct 2 18:46:57.320821 systemd[1]: nvidia.service: Deactivated successfully.
Oct 2 18:46:57.332173 env[1730]: time="2023-10-02T18:46:57.332109624Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Oct 2 18:46:57.332409 env[1730]: time="2023-10-02T18:46:57.332370942Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
Oct 2 18:46:57.332761 env[1730]: time="2023-10-02T18:46:57.332523621Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Oct 2 18:46:57.333040 env[1730]: time="2023-10-02T18:46:57.332993410Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
Oct 2 18:46:57.333193 env[1730]: time="2023-10-02T18:46:57.333159656Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Oct 2 18:46:57.333360 env[1730]: time="2023-10-02T18:46:57.333327826Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Oct 2 18:46:57.333498 env[1730]: time="2023-10-02T18:46:57.333462718Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Oct 2 18:46:57.334280 env[1730]: time="2023-10-02T18:46:57.334206874Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Oct 2 18:46:57.334519 env[1730]: time="2023-10-02T18:46:57.334475768Z" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1
Oct 2 18:46:57.334718 env[1730]: time="2023-10-02T18:46:57.334677901Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Oct 2 18:46:57.334872 env[1730]: time="2023-10-02T18:46:57.334836210Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Oct 2 18:46:57.335018 env[1730]: time="2023-10-02T18:46:57.334984608Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Oct 2 18:46:57.335430 env[1730]: time="2023-10-02T18:46:57.335379470Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Oct 2 18:46:57.335966 env[1730]: time="2023-10-02T18:46:57.335905135Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Oct 2 18:46:57.340398 env[1730]: time="2023-10-02T18:46:57.340304281Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Oct 2 18:46:57.340577 env[1730]: time="2023-10-02T18:46:57.340408842Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Oct 2 18:46:57.340577 env[1730]: time="2023-10-02T18:46:57.340445800Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Oct 2 18:46:57.340702 env[1730]: time="2023-10-02T18:46:57.340644422Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Oct 2 18:46:57.340702 env[1730]: time="2023-10-02T18:46:57.340688115Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Oct 2 18:46:57.340854 env[1730]: time="2023-10-02T18:46:57.340725157Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Oct 2 18:46:57.340854 env[1730]: time="2023-10-02T18:46:57.340757654Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Oct 2 18:46:57.340854 env[1730]: time="2023-10-02T18:46:57.340789549Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Oct 2 18:46:57.340854 env[1730]: time="2023-10-02T18:46:57.340822045Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Oct 2 18:46:57.341057 env[1730]: time="2023-10-02T18:46:57.340853206Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Oct 2 18:46:57.341057 env[1730]: time="2023-10-02T18:46:57.340885883Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Oct 2 18:46:57.341057 env[1730]: time="2023-10-02T18:46:57.340933713Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
Oct 2 18:46:57.341354 env[1730]: time="2023-10-02T18:46:57.341264413Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Oct 2 18:46:57.341453 env[1730]: time="2023-10-02T18:46:57.341357368Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Oct 2 18:46:57.341453 env[1730]: time="2023-10-02T18:46:57.341421230Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Oct 2 18:46:57.341606 env[1730]: time="2023-10-02T18:46:57.341456757Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
Oct 2 18:46:57.341606 env[1730]: time="2023-10-02T18:46:57.341493571Z" 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 18:46:57.341606 env[1730]: time="2023-10-02T18:46:57.341529940Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
Oct 2 18:46:57.341606 env[1730]: time="2023-10-02T18:46:57.341594223Z" level=error msg="failed to initialize a tracing processor \"otlp\"" error="no OpenTelemetry endpoint: skip plugin"
Oct 2 18:46:57.341837 env[1730]: time="2023-10-02T18:46:57.341686011Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1
Oct 2 18:46:57.342230 env[1730]: time="2023-10-02T18:46:57.342089075Z" 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 18:46:57.342230 env[1730]: time="2023-10-02T18:46:57.342221658Z" level=info msg="Connect containerd service"
Oct 2 18:46:57.343776 env[1730]: time="2023-10-02T18:46:57.342305485Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\""
Oct 2 18:46:57.344003 env[1730]: time="2023-10-02T18:46:57.343874591Z" 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 18:46:57.344923 env[1730]: time="2023-10-02T18:46:57.344847062Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc
Oct 2 18:46:57.345061 env[1730]: time="2023-10-02T18:46:57.344994294Z" level=info msg=serving... address=/run/containerd/containerd.sock
Oct 2 18:46:57.348285 env[1730]: time="2023-10-02T18:46:57.345105048Z" level=info msg="containerd successfully booted in 0.229432s"
Oct 2 18:46:57.345242 systemd[1]: Started containerd.service.
Oct 2 18:46:57.401728 env[1730]: time="2023-10-02T18:46:57.401624269Z" level=info msg="Start subscribing containerd event"
Oct 2 18:46:57.401979 env[1730]: time="2023-10-02T18:46:57.401942545Z" level=info msg="Start recovering state"
Oct 2 18:46:57.402217 env[1730]: time="2023-10-02T18:46:57.402185076Z" level=info msg="Start event monitor"
Oct 2 18:46:57.402517 env[1730]: time="2023-10-02T18:46:57.402473923Z" level=info msg="Start snapshots syncer"
Oct 2 18:46:57.402765 env[1730]: time="2023-10-02T18:46:57.402724200Z" level=info msg="Start cni network conf syncer for default"
Oct 2 18:46:57.402921 env[1730]: time="2023-10-02T18:46:57.402886621Z" level=info msg="Start streaming server"
Oct 2 18:46:57.441844 dbus-daemon[1706]: [system] Successfully activated service 'org.freedesktop.hostname1'
Oct 2 18:46:57.442103 systemd[1]: Started systemd-hostnamed.service.
Oct 2 18:46:57.446360 dbus-daemon[1706]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.6' (uid=0 pid=1748 comm="/usr/lib/systemd/systemd-hostnamed" label="system_u:system_r:kernel_t:s0")
Oct 2 18:46:57.453068 systemd[1]: Starting polkit.service...
Oct 2 18:46:57.518667 polkitd[1822]: Started polkitd version 121
Oct 2 18:46:57.533031 coreos-metadata[1704]: Oct 02 18:46:57.532 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1
Oct 2 18:46:57.534406 coreos-metadata[1704]: Oct 02 18:46:57.534 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/public-keys: Attempt #1
Oct 2 18:46:57.536239 coreos-metadata[1704]: Oct 02 18:46:57.536 INFO Fetch successful
Oct 2 18:46:57.536386 coreos-metadata[1704]: Oct 02 18:46:57.536 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/public-keys/0/openssh-key: Attempt #1
Oct 2 18:46:57.538803 coreos-metadata[1704]: Oct 02 18:46:57.538 INFO Fetch successful
Oct 2 18:46:57.541817 unknown[1704]: wrote ssh authorized keys file for user: core
Oct 2 18:46:57.562267 polkitd[1822]: Loading rules from directory /etc/polkit-1/rules.d
Oct 2 18:46:57.562398 polkitd[1822]: Loading rules from directory /usr/share/polkit-1/rules.d
Oct 2 18:46:57.573180 polkitd[1822]: Finished loading, compiling and executing 2 rules
Oct 2 18:46:57.574920 dbus-daemon[1706]: [system] Successfully activated service 'org.freedesktop.PolicyKit1'
Oct 2 18:46:57.575205 systemd[1]: Started polkit.service.
Oct 2 18:46:57.578701 polkitd[1822]: Acquired the name org.freedesktop.PolicyKit1 on the system bus
Oct 2 18:46:57.589314 update-ssh-keys[1834]: Updated "/home/core/.ssh/authorized_keys"
Oct 2 18:46:57.590663 systemd[1]: Finished coreos-metadata-sshkeys@core.service.
Oct 2 18:46:57.616192 systemd-hostnamed[1748]: Hostname set to <ip-172-31-20-179> (transient)
Oct 2 18:46:57.616390 systemd-resolved[1676]: System hostname changed to 'ip-172-31-20-179'.
Oct 2 18:46:57.683609 coreos-metadata[1705]: Oct 02 18:46:57.683 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1
Oct 2 18:46:57.688801 coreos-metadata[1705]: Oct 02 18:46:57.688 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/instance-id: Attempt #1
Oct 2 18:46:57.689906 coreos-metadata[1705]: Oct 02 18:46:57.689 INFO Fetch successful
Oct 2 18:46:57.689906 coreos-metadata[1705]: Oct 02 18:46:57.689 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/instance-type: Attempt #1
Oct 2 18:46:57.691359 coreos-metadata[1705]: Oct 02 18:46:57.690 INFO Fetch successful
Oct 2 18:46:57.691359 coreos-metadata[1705]: Oct 02 18:46:57.691 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/local-ipv4: Attempt #1
Oct 2 18:46:57.692545 coreos-metadata[1705]: Oct 02 18:46:57.692 INFO Fetch successful
Oct 2 18:46:57.692545 coreos-metadata[1705]: Oct 02 18:46:57.692 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/public-ipv4: Attempt #1
Oct 2 18:46:57.693804 coreos-metadata[1705]: Oct 02 18:46:57.693 INFO Fetch successful
Oct 2 18:46:57.693804 coreos-metadata[1705]: Oct 02 18:46:57.693 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/placement/availability-zone: Attempt #1
Oct 2 18:46:57.694850 coreos-metadata[1705]: Oct 02 18:46:57.694 INFO Fetch successful
Oct 2 18:46:57.694850 coreos-metadata[1705]: Oct 02 18:46:57.694 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/hostname: Attempt #1
Oct 2 18:46:57.695863 coreos-metadata[1705]: Oct 02 18:46:57.695 INFO Fetch successful
Oct 2 18:46:57.695863 coreos-metadata[1705]: Oct 02 18:46:57.695 INFO Fetching http://169.254.169.254/2019-10-01/meta-data/public-hostname: Attempt #1
Oct 2 18:46:57.696887 coreos-metadata[1705]: Oct 02 18:46:57.696 INFO Fetch successful
Oct 2 18:46:57.696887 coreos-metadata[1705]: Oct 02 18:46:57.696 INFO Fetching http://169.254.169.254/2019-10-01/dynamic/instance-identity/document: Attempt #1
Oct 2 18:46:57.698006 coreos-metadata[1705]: Oct 02 18:46:57.697 INFO Fetch successful
Oct 2 18:46:57.699735 amazon-ssm-agent[1702]: 2023-10-02 18:46:57 INFO Entering SSM Agent hibernate - AccessDeniedException: User: arn:aws:sts::075585003325:assumed-role/jenkins-test/i-0455b4fbbb78a9d4f is not authorized to perform: ssm:UpdateInstanceInformation on resource: arn:aws:ec2:us-west-2:075585003325:instance/i-0455b4fbbb78a9d4f because no identity-based policy allows the ssm:UpdateInstanceInformation action
Oct 2 18:46:57.699735 amazon-ssm-agent[1702]: status code: 400, request id: 2ddec868-4ea6-4489-b79b-a3666b4cd98a
Oct 2 18:46:57.700200 amazon-ssm-agent[1702]: 2023-10-02 18:46:57 INFO Agent is in hibernate mode. Reducing logging. Logging will be reduced to one log per backoff period
Oct 2 18:46:57.754715 systemd[1]: Finished coreos-metadata.service.
Oct 2 18:46:57.759822 systemd[1]: Starting etcd-member.service...
Oct 2 18:46:57.947854 systemd[1]: Starting docker.service...
Oct 2 18:46:58.189085 env[1892]: time="2023-10-02T18:46:58.188990009Z" level=info msg="Starting up"
Oct 2 18:46:58.195845 env[1892]: time="2023-10-02T18:46:58.194223192Z" level=info msg="parsed scheme: \"unix\"" module=grpc
Oct 2 18:46:58.195845 env[1892]: time="2023-10-02T18:46:58.194270703Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
Oct 2 18:46:58.195845 env[1892]: time="2023-10-02T18:46:58.194313525Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/libcontainerd/docker-containerd.sock <nil> 0 <nil>}] <nil> <nil>}" module=grpc
Oct 2 18:46:58.195845 env[1892]: time="2023-10-02T18:46:58.194337503Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
Oct 2 18:46:58.200098 env[1892]: time="2023-10-02T18:46:58.199530305Z" level=info msg="parsed scheme: \"unix\"" module=grpc
Oct 2 18:46:58.200098 env[1892]: time="2023-10-02T18:46:58.199629933Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
Oct 2 18:46:58.200098 env[1892]: time="2023-10-02T18:46:58.199664036Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/libcontainerd/docker-containerd.sock <nil> 0 <nil>}] <nil> <nil>}" module=grpc
Oct 2 18:46:58.200098 env[1892]: time="2023-10-02T18:46:58.199685994Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
Oct 2 18:46:58.210949 systemd[1]: var-lib-docker-check\x2doverlayfs\x2dsupport2273932672-merged.mount: Deactivated successfully.
Oct 2 18:46:58.272687 env[1892]: time="2023-10-02T18:46:58.272002907Z" level=info msg="Loading containers: start."
Oct 2 18:46:58.439362 locksmithd[1774]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot"
Oct 2 18:46:58.537588 kernel: Initializing XFRM netlink socket
Oct 2 18:46:58.622067 env[1892]: time="2023-10-02T18:46:58.622006801Z" 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 18:46:58.624057 (udev-worker)[1556]: Network interface NamePolicy= disabled on kernel command line.
Oct 2 18:46:58.777976 systemd-networkd[1535]: docker0: Link UP
Oct 2 18:46:58.804547 env[1892]: time="2023-10-02T18:46:58.804475873Z" level=info msg="Loading containers: done."
Oct 2 18:46:58.830722 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck2725161760-merged.mount: Deactivated successfully.
Oct 2 18:46:58.849450 env[1892]: time="2023-10-02T18:46:58.849393218Z" 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 18:46:58.850234 env[1892]: time="2023-10-02T18:46:58.850201328Z" level=info msg="Docker daemon" commit=112bdf3343 graphdriver(s)=overlay2 version=20.10.23
Oct 2 18:46:58.850657 env[1892]: time="2023-10-02T18:46:58.850608557Z" level=info msg="Daemon has completed initialization"
Oct 2 18:46:58.884437 systemd[1]: Started docker.service.
Oct 2 18:46:58.906120 env[1892]: time="2023-10-02T18:46:58.905933718Z" level=info msg="API listen on /run/docker.sock"
Oct 2 18:46:58.908532 etcd-wrapper[1871]: Error response from daemon: No such container: etcd-member
Oct 2 18:46:58.977886 etcd-wrapper[2015]: Error: No such container: etcd-member
Oct 2 18:46:59.184808 etcd-wrapper[2032]: Unable to find image 'quay.io/coreos/etcd:v3.5.0-arm64' locally
Oct 2 18:47:00.481686 etcd-wrapper[2032]: v3.5.0-arm64: Pulling from coreos/etcd
Oct 2 18:47:00.482676 etcd-wrapper[2032]: 6022c6b01d76: Pulling fs layer
Oct 2 18:47:00.482946 etcd-wrapper[2032]: 1d97b1ec77fc: Pulling fs layer
Oct 2 18:47:00.483178 etcd-wrapper[2032]: 99ef4b690837: Pulling fs layer
Oct 2 18:47:00.483419 etcd-wrapper[2032]: 435b7bf0967c: Pulling fs layer
Oct 2 18:47:00.483659 etcd-wrapper[2032]: 17e4a5f7c1b5: Pulling fs layer
Oct 2 18:47:00.483895 etcd-wrapper[2032]: 5b0292999756: Pulling fs layer
Oct 2 18:47:00.486860 etcd-wrapper[2032]: 435b7bf0967c: Waiting
Oct 2 18:47:00.487818 etcd-wrapper[2032]: 17e4a5f7c1b5: Waiting
Oct 2 18:47:00.488103 etcd-wrapper[2032]: 5b0292999756: Waiting
Oct 2 18:47:01.017963 sshd_keygen[1729]: ssh-keygen: generating new host keys: RSA ECDSA ED25519
Oct 2 18:47:01.086330 systemd[1]: Finished sshd-keygen.service.
Oct 2 18:47:01.093991 systemd[1]: Starting issuegen.service...
Oct 2 18:47:01.122736 systemd[1]: issuegen.service: Deactivated successfully.
Oct 2 18:47:01.123183 systemd[1]: Finished issuegen.service.
Oct 2 18:47:01.128353 systemd[1]: Starting systemd-user-sessions.service...
Oct 2 18:47:01.156673 systemd[1]: Finished systemd-user-sessions.service.
Oct 2 18:47:01.163009 systemd[1]: Started getty@tty1.service.
Oct 2 18:47:01.170404 systemd[1]: Started serial-getty@ttyS0.service.
Oct 2 18:47:01.173251 systemd[1]: Reached target getty.target.
Oct 2 18:47:01.270192 etcd-wrapper[2032]: 99ef4b690837: Verifying Checksum
Oct 2 18:47:01.270368 etcd-wrapper[2032]: 99ef4b690837: Download complete
Oct 2 18:47:01.285174 etcd-wrapper[2032]: 1d97b1ec77fc: Verifying Checksum
Oct 2 18:47:01.285368 etcd-wrapper[2032]: 1d97b1ec77fc: Download complete
Oct 2 18:47:01.376231 etcd-wrapper[2032]: 6022c6b01d76: Verifying Checksum
Oct 2 18:47:01.376417 etcd-wrapper[2032]: 6022c6b01d76: Download complete
Oct 2 18:47:01.629684 etcd-wrapper[2032]: 17e4a5f7c1b5: Verifying Checksum
Oct 2 18:47:01.630429 etcd-wrapper[2032]: 17e4a5f7c1b5: Download complete
Oct 2 18:47:01.762493 etcd-wrapper[2032]: 435b7bf0967c: Verifying Checksum
Oct 2 18:47:01.762699 etcd-wrapper[2032]: 435b7bf0967c: Download complete
Oct 2 18:47:01.786688 etcd-wrapper[2032]: 5b0292999756: Verifying Checksum
Oct 2 18:47:01.786875 etcd-wrapper[2032]: 5b0292999756: Download complete
Oct 2 18:47:03.263123 etcd-wrapper[2032]: 6022c6b01d76: Pull complete
Oct 2 18:47:03.770354 systemd[1]: var-lib-docker-overlay2-f8c0ecc9162da1a445db2169deb65c1adf694150abddcdf0ddb5001b24aaafe5-merged.mount: Deactivated successfully.
Oct 2 18:47:04.453698 etcd-wrapper[2032]: 1d97b1ec77fc: Pull complete
Oct 2 18:47:04.848260 systemd[1]: var-lib-docker-overlay2-be2e79237ed6d65686d70e35da5e95b58dd9db0a07eb3b8e05c8772f06562332-merged.mount: Deactivated successfully.
Oct 2 18:47:04.989082 etcd-wrapper[2032]: 99ef4b690837: Pull complete
Oct 2 18:47:05.359198 systemd[1]: var-lib-docker-overlay2-8c1cc4e61ba07845c94169d7c560e3176ba84a4683f80ba10c9783095981d6e5-merged.mount: Deactivated successfully.
Oct 2 18:47:05.562092 etcd-wrapper[2032]: 435b7bf0967c: Pull complete
Oct 2 18:47:05.676247 systemd[1]: var-lib-docker-overlay2-725a9a8ccad9283f2094a17870f277ce51e74183b01942784dab78b25b8832d8-merged.mount: Deactivated successfully.
Oct 2 18:47:05.691597 etcd-wrapper[2032]: 17e4a5f7c1b5: Pull complete
Oct 2 18:47:05.806318 systemd[1]: var-lib-docker-overlay2-d3bd8a5415549f52fea25000bd2df5d9e17db32c2904f779bcdda77dc2a30765-merged.mount: Deactivated successfully.
Oct 2 18:47:05.828491 etcd-wrapper[2032]: 5b0292999756: Pull complete
Oct 2 18:47:05.840063 etcd-wrapper[2032]: Digest: sha256:96255d1ad67e7afcea46b4dc1b7e74024b9f6971193cb7316b093b3b5671efb0
Oct 2 18:47:05.843670 etcd-wrapper[2032]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.0-arm64
Oct 2 18:47:06.042048 systemd[1]: Created slice system-sshd.slice.
Oct 2 18:47:06.047892 systemd[1]: Started sshd@0-172.31.20.179:22-139.178.89.65:41014.service.
Oct 2 18:47:06.068836 env[1730]: time="2023-10-02T18:47:06.068409123Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
Oct 2 18:47:06.068836 env[1730]: time="2023-10-02T18:47:06.068481802Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
Oct 2 18:47:06.068836 env[1730]: time="2023-10-02T18:47:06.068507442Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Oct 2 18:47:06.069511 env[1730]: time="2023-10-02T18:47:06.068990143Z" level=info msg="starting signal loop" namespace=moby path=/run/containerd/io.containerd.runtime.v2.task/moby/d319faee51c45c58a0610c4423551671ca789907e932f492b39c6b2aa33c3ea1 pid=2109 runtime=io.containerd.runc.v2
Oct 2 18:47:06.101288 systemd[1]: Started docker-d319faee51c45c58a0610c4423551671ca789907e932f492b39c6b2aa33c3ea1.scope.
Oct 2 18:47:06.250884 sshd[2105]: Accepted publickey for core from 139.178.89.65 port 41014 ssh2: RSA SHA256:ePkK8jKoGlhN3AxcTQ2G+RQZHD5kDZhw675IJmRySH8
Oct 2 18:47:06.257344 sshd[2105]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
Oct 2 18:47:06.277804 systemd[1]: Created slice user-500.slice.
Oct 2 18:47:06.280494 systemd[1]: Starting user-runtime-dir@500.service...
Oct 2 18:47:06.291804 etcd-wrapper[2032]: {"level":"info","ts":1696272426.2907772,"caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"}
Oct 2 18:47:06.291804 etcd-wrapper[2032]: {"level":"info","ts":1696272426.2913458,"caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"ec2f44b294607a20d9a4b553620a012b"}
Oct 2 18:47:06.291804 etcd-wrapper[2032]: {"level":"warn","ts":1696272426.29144,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"}
Oct 2 18:47:06.291804 etcd-wrapper[2032]: {"level":"warn","ts":1696272426.2914927,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.0-arm64"}
Oct 2 18:47:06.291804 etcd-wrapper[2032]: {"level":"warn","ts":1696272426.2915418,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"}
Oct 2 18:47:06.291804 etcd-wrapper[2032]: {"level":"warn","ts":1696272426.291608,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"}
Oct 2 18:47:06.292398 etcd-wrapper[2032]: {"level":"warn","ts":1696272426.291659,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_UNSUPPORTED_ARCH=arm64"}
Oct 2 18:47:06.293024 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:06.292Z","caller":"etcdmain/etcd.go:72","msg":"Running: ","args":["/usr/local/bin/etcd","--listen-peer-urls=http://172.31.20.179:2380","--listen-client-urls=http://0.0.0.0:2379","--initial-advertise-peer-urls=http://172.31.20.179:2380","--advertise-client-urls=http://172.31.20.179:2379","--discovery=https://discovery.etcd.io/df9b2faccfc516e8b10772bf8cbd0d12"]}
Oct 2 18:47:06.293826 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:06.292Z","caller":"embed/etcd.go:131","msg":"configuring peer listeners","listen-peer-urls":["http://172.31.20.179:2380"]}
Oct 2 18:47:06.294498 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:06.294Z","caller":"embed/etcd.go:139","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]}
Oct 2 18:47:06.295700 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:06.294Z","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":"ec2f44b294607a20d9a4b553620a012b","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://172.31.20.179:2380"],"listen-peer-urls":["http://172.31.20.179:2380"],"advertise-client-urls":["http://172.31.20.179:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"ec2f44b294607a20d9a4b553620a012b=http://172.31.20.179:2380","initial-cluster-state":"new","initial-cluster-token":"https://discovery.etcd.io/df9b2faccfc516e8b10772bf8cbd0d12","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":"https://discovery.etcd.io/df9b2faccfc516e8b10772bf8cbd0d12","discovery-proxy":"","downgrade-check-interval":"5s"}
Oct 2 18:47:06.297410 systemd-logind[1713]: New session 1 of user core.
Oct 2 18:47:06.304410 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:06.304Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.180409ms"}
Oct 2 18:47:06.313277 systemd[1]: Finished user-runtime-dir@500.service.
Oct 2 18:47:06.316920 systemd[1]: Starting user@500.service...
Oct 2 18:47:06.328496 (systemd)[2150]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0)
Oct 2 18:47:06.551744 systemd[2150]: Queued start job for default target default.target.
Oct 2 18:47:06.554103 systemd[2150]: Reached target paths.target.
Oct 2 18:47:06.554358 systemd[2150]: Reached target sockets.target.
Oct 2 18:47:06.554515 systemd[2150]: Reached target timers.target.
Oct 2 18:47:06.554741 systemd[2150]: Reached target basic.target.
Oct 2 18:47:06.555045 systemd[1]: Started user@500.service.
Oct 2 18:47:06.556855 systemd[1]: Started session-1.scope.
Oct 2 18:47:06.558314 systemd[2150]: Reached target default.target.
Oct 2 18:47:06.558671 systemd[2150]: Startup finished in 209ms.
Oct 2 18:47:06.575166 systemd[1]: var-lib-docker-overlay2-5f9baea413458d48324d65a9112e79aa9615b0cd987e4623a576aaa6ddff93cb\x2dinit-merged.mount: Deactivated successfully.
Oct 2 18:47:06.721371 systemd[1]: Started sshd@1-172.31.20.179:22-139.178.89.65:41024.service.
Oct 2 18:47:06.898327 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:06.897Z","caller":"v2discovery/discovery.go:336","msg":"found self from discovery server","discovery-url":"https://discovery.etcd.io","self":"7ca6f47e81dfb10f"}
Oct 2 18:47:06.898327 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:06.897Z","caller":"v2discovery/discovery.go:352","msg":"found peers from discovery server; waiting for more","discovery-url":"https://discovery.etcd.io","found-peers":1,"needed-peers":2}
Oct 2 18:47:06.912860 sshd[2159]: Accepted publickey for core from 139.178.89.65 port 41024 ssh2: RSA SHA256:ePkK8jKoGlhN3AxcTQ2G+RQZHD5kDZhw675IJmRySH8
Oct 2 18:47:06.916221 sshd[2159]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
Oct 2 18:47:06.925896 systemd[1]: Started session-2.scope.
Oct 2 18:47:06.926831 systemd-logind[1713]: New session 2 of user core.
Oct 2 18:47:07.076325 sshd[2159]: pam_unix(sshd:session): session closed for user core
Oct 2 18:47:07.082080 systemd[1]: sshd@1-172.31.20.179:22-139.178.89.65:41024.service: Deactivated successfully.
Oct 2 18:47:07.083412 systemd[1]: session-2.scope: Deactivated successfully.
Oct 2 18:47:07.084509 systemd-logind[1713]: Session 2 logged out. Waiting for processes to exit.
Oct 2 18:47:07.086033 systemd-logind[1713]: Removed session 2.
Oct 2 18:47:07.106677 systemd[1]: Started sshd@2-172.31.20.179:22-139.178.89.65:41038.service.
Oct 2 18:47:07.288822 sshd[2165]: Accepted publickey for core from 139.178.89.65 port 41038 ssh2: RSA SHA256:ePkK8jKoGlhN3AxcTQ2G+RQZHD5kDZhw675IJmRySH8
Oct 2 18:47:07.291204 sshd[2165]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
Oct 2 18:47:07.299764 systemd-logind[1713]: New session 3 of user core.
Oct 2 18:47:07.300763 systemd[1]: Started session-3.scope.
Oct 2 18:47:07.405677 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.405Z","caller":"v2discovery/discovery.go:371","msg":"found peer from discovery server","discovery-url":"https://discovery.etcd.io","peer":"f5c3ff90eef4c907"}
Oct 2 18:47:07.405677 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.405Z","caller":"v2discovery/discovery.go:352","msg":"found peers from discovery server; waiting for more","discovery-url":"https://discovery.etcd.io","found-peers":2,"needed-peers":1}
Oct 2 18:47:07.447171 sshd[2165]: pam_unix(sshd:session): session closed for user core
Oct 2 18:47:07.453728 systemd-logind[1713]: Session 3 logged out. Waiting for processes to exit.
Oct 2 18:47:07.454644 systemd[1]: sshd@2-172.31.20.179:22-139.178.89.65:41038.service: Deactivated successfully.
Oct 2 18:47:07.455875 systemd[1]: session-3.scope: Deactivated successfully.
Oct 2 18:47:07.457131 systemd-logind[1713]: Removed session 3.
Oct 2 18:47:07.686891 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.686Z","caller":"v2discovery/discovery.go:371","msg":"found peer from discovery server","discovery-url":"https://discovery.etcd.io","peer":"ba97dc4e8d2d41bb"}
Oct 2 18:47:07.686891 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.686Z","caller":"v2discovery/discovery.go:378","msg":"found all needed peers from discovery server","discovery-url":"https://discovery.etcd.io","found-peers":3}
Oct 2 18:47:07.691525 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.691Z","caller":"etcdserver/raft.go:448","msg":"starting local member","local-member-id":"7ca6f47e81dfb10f","cluster-id":"7ed2db375b9debe2"}
Oct 2 18:47:07.692123 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7ca6f47e81dfb10f switched to configuration voters=()"}
Oct 2 18:47:07.692123 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7ca6f47e81dfb10f became follower at term 0"}
Oct 2 18:47:07.692600 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.692Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7ca6f47e81dfb10f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
Oct 2 18:47:07.693066 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.692Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7ca6f47e81dfb10f became follower at term 1"}
Oct 2 18:47:07.693602 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.693Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7ca6f47e81dfb10f switched to configuration voters=(8982135331019010319)"}
Oct 2 18:47:07.693865 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.693Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7ca6f47e81dfb10f switched to configuration voters=(8982135331019010319 13445457442472018363)"}
Oct 2 18:47:07.693865 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.693Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7ca6f47e81dfb10f switched to configuration voters=(8982135331019010319 13445457442472018363 17709279157700315399)"}
Oct 2 18:47:07.695910 etcd-wrapper[2032]: {"level":"warn","ts":"2023-10-02T18:47:07.695Z","caller":"auth/store.go:1220","msg":"simple token is not cryptographically signed"}
Oct 2 18:47:07.698191 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.697Z","caller":"mvcc/kvstore.go:415","msg":"kvstore restored","current-rev":1}
Oct 2 18:47:07.699637 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.699Z","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 18:47:07.700939 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.700Z","caller":"rafthttp/peer.go:133","msg":"starting remote peer","remote-peer-id":"ba97dc4e8d2d41bb"}
Oct 2 18:47:07.701317 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.700Z","caller":"rafthttp/pipeline.go:72","msg":"started HTTP pipelining with remote peer","local-member-id":"7ca6f47e81dfb10f","remote-peer-id":"ba97dc4e8d2d41bb"}
Oct 2 18:47:07.703582 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.703Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"7ca6f47e81dfb10f","remote-peer-id":"ba97dc4e8d2d41bb"}
Oct 2 18:47:07.705927 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.705Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"7ca6f47e81dfb10f","remote-peer-id":"ba97dc4e8d2d41bb"}
Oct 2 18:47:07.708210 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.707Z","caller":"rafthttp/peer.go:137","msg":"started remote peer","remote-peer-id":"ba97dc4e8d2d41bb"}
Oct 2 18:47:07.708210 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.707Z","caller":"rafthttp/transport.go:317","msg":"added remote peer","local-member-id":"7ca6f47e81dfb10f","remote-peer-id":"ba97dc4e8d2d41bb","remote-peer-urls":["http://172.31.29.65:2380"]}
Oct 2 18:47:07.708499 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.707Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream Message","local-member-id":"7ca6f47e81dfb10f","remote-peer-id":"ba97dc4e8d2d41bb"}
Oct 2 18:47:07.708956 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.708Z","caller":"rafthttp/peer.go:133","msg":"starting remote peer","remote-peer-id":"f5c3ff90eef4c907"}
Oct 2 18:47:07.709148 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.708Z","caller":"rafthttp/pipeline.go:72","msg":"started HTTP pipelining with remote peer","local-member-id":"7ca6f47e81dfb10f","remote-peer-id":"f5c3ff90eef4c907"}
Oct 2 18:47:07.709582 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.707Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"7ca6f47e81dfb10f","remote-peer-id":"ba97dc4e8d2d41bb"}
Oct 2 18:47:07.710408 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.709Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"7ca6f47e81dfb10f","remote-peer-id":"f5c3ff90eef4c907"}
Oct 2 18:47:07.718345 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.717Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"7ca6f47e81dfb10f","remote-peer-id":"f5c3ff90eef4c907"}
Oct 2 18:47:07.720870 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.720Z","caller":"rafthttp/peer.go:137","msg":"started remote peer","remote-peer-id":"f5c3ff90eef4c907"}
Oct 2 18:47:07.721330 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.720Z","caller":"rafthttp/transport.go:317","msg":"added remote peer","local-member-id":"7ca6f47e81dfb10f","remote-peer-id":"f5c3ff90eef4c907","remote-peer-urls":["http://172.31.20.233:2380"]}
Oct 2 18:47:07.721845 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.721Z","caller":"etcdserver/server.go:843","msg":"starting etcd server","local-member-id":"7ca6f47e81dfb10f","local-server-version":"3.5.0","cluster-version":"to_be_decided"}
Oct 2 18:47:07.724394 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.723Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"7ca6f47e81dfb10f","remote-peer-id":"f5c3ff90eef4c907"}
Oct 2 18:47:07.725280 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.724Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream Message","local-member-id":"7ca6f47e81dfb10f","remote-peer-id":"f5c3ff90eef4c907"}
Oct 2 18:47:07.726997 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.726Z","caller":"etcdserver/server.go:744","msg":"starting initial election tick advance","election-ticks":10}
Oct 2 18:47:07.728005 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.727Z","caller":"embed/etcd.go:276","msg":"now serving peer/client/metrics","local-member-id":"7ca6f47e81dfb10f","initial-advertise-peer-urls":["http://172.31.20.179:2380"],"listen-peer-urls":["http://172.31.20.179:2380"],"advertise-client-urls":["http://172.31.20.179:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]}
Oct 2 18:47:07.728653 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.727Z","caller":"embed/etcd.go:580","msg":"serving peer traffic","address":"172.31.20.179:2380"}
Oct 2 18:47:07.728653 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.728Z","caller":"embed/etcd.go:552","msg":"cmux::serve","address":"172.31.20.179:2380"}
Oct 2 18:47:07.729088 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7ca6f47e81dfb10f switched to configuration voters=(8982135331019010319 13445457442472018363 17709279157700315399)"}
Oct 2 18:47:07.729704 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.729Z","caller":"membership/cluster.go:393","msg":"added member","cluster-id":"7ed2db375b9debe2","local-member-id":"7ca6f47e81dfb10f","added-peer-id":"7ca6f47e81dfb10f","added-peer-peer-urls":["http://172.31.20.179:2380"]}
Oct 2 18:47:07.730324 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.729Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"7ca6f47e81dfb10f","to":"f5c3ff90eef4c907","stream-type":"stream Message"}
Oct 2 18:47:07.730675 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.730Z","caller":"rafthttp/peer_status.go:53","msg":"peer became active","peer-id":"f5c3ff90eef4c907"}
Oct 2 18:47:07.730986 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.730Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream Message","local-member-id":"7ca6f47e81dfb10f","remote-peer-id":"f5c3ff90eef4c907"}
Oct 2 18:47:07.731241 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.730Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"7ca6f47e81dfb10f","to":"f5c3ff90eef4c907","stream-type":"stream MsgApp v2"}
Oct 2 18:47:07.731241 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.730Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","local-member-id":"7ca6f47e81dfb10f","remote-peer-id":"f5c3ff90eef4c907"}
Oct 2 18:47:07.731774 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7ca6f47e81dfb10f switched to configuration voters=(8982135331019010319 13445457442472018363 17709279157700315399)"}
Oct 2 18:47:07.732225 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.731Z","caller":"membership/cluster.go:393","msg":"added member","cluster-id":"7ed2db375b9debe2","local-member-id":"7ca6f47e81dfb10f","added-peer-id":"ba97dc4e8d2d41bb","added-peer-peer-urls":["http://172.31.29.65:2380"]}
Oct 2 18:47:07.732816 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7ca6f47e81dfb10f switched to configuration voters=(8982135331019010319 13445457442472018363 17709279157700315399)"}
Oct 2 18:47:07.733187 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.732Z","caller":"membership/cluster.go:393","msg":"added member","cluster-id":"7ed2db375b9debe2","local-member-id":"7ca6f47e81dfb10f","added-peer-id":"f5c3ff90eef4c907","added-peer-peer-urls":["http://172.31.20.233:2380"]}
Oct 2 18:47:07.736570 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.736Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"7ca6f47e81dfb10f","remote-peer-id":"f5c3ff90eef4c907"}
Oct 2 18:47:07.737010 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.736Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"7ca6f47e81dfb10f","remote-peer-id":"f5c3ff90eef4c907"}
Oct 2 18:47:07.865110 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.864Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"7ca6f47e81dfb10f","to":"ba97dc4e8d2d41bb","stream-type":"stream MsgApp v2"}
Oct 2 18:47:07.865110 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.864Z","caller":"rafthttp/peer_status.go:53","msg":"peer became active","peer-id":"ba97dc4e8d2d41bb"}
Oct 2 18:47:07.865110 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.864Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","local-member-id":"7ca6f47e81dfb10f","remote-peer-id":"ba97dc4e8d2d41bb"}
Oct 2 18:47:07.865819 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.865Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"7ca6f47e81dfb10f","to":"ba97dc4e8d2d41bb","stream-type":"stream Message"}
Oct 2 18:47:07.865819 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.865Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream Message","local-member-id":"7ca6f47e81dfb10f","remote-peer-id":"ba97dc4e8d2d41bb"}
Oct 2 18:47:07.876735 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.876Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"7ca6f47e81dfb10f","remote-peer-id":"ba97dc4e8d2d41bb"}
Oct 2 18:47:07.877280 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.876Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"7ca6f47e81dfb10f","remote-peer-id":"ba97dc4e8d2d41bb"}
Oct 2 18:47:07.878360 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:07.878Z","caller":"etcdserver/server.go:767","msg":"initialized peer connections; fast-forwarding election ticks","local-member-id":"7ca6f47e81dfb10f","forward-ticks":8,"forward-duration":"800ms","election-ticks":10,"election-timeout":"1s","active-remote-members":2}
Oct 2 18:47:08.017282 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:08.016Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7ca6f47e81dfb10f [logterm: 1, index: 3, vote: 0] cast MsgPreVote for f5c3ff90eef4c907 [logterm: 1, index: 3] at term 1"}
Oct 2 18:47:08.020234 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:08.019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7ca6f47e81dfb10f [term: 1] received a MsgVote message with higher term from f5c3ff90eef4c907 [term: 2]"}
Oct 2 18:47:08.020234 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:08.019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7ca6f47e81dfb10f became follower at term 2"}
Oct 2 18:47:08.020234 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:08.019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7ca6f47e81dfb10f [logterm: 1, index: 3, vote: 0] cast MsgVote for f5c3ff90eef4c907 [logterm: 1, index: 3] at term 2"}
Oct 2 18:47:08.021862 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:08.021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7ca6f47e81dfb10f elected leader f5c3ff90eef4c907 at term 2"}
Oct 2 18:47:08.029065 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:08.028Z","caller":"etcdserver/server.go:2027","msg":"published local member to cluster through raft","local-member-id":"7ca6f47e81dfb10f","local-member-attributes":"{Name:ec2f44b294607a20d9a4b553620a012b ClientURLs:[http://172.31.20.179:2379]}","request-path":"/0/members/7ca6f47e81dfb10f/attributes","cluster-id":"7ed2db375b9debe2","publish-timeout":"7s"}
Oct 2 18:47:08.030158 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:08.029Z","caller":"embed/serve.go:98","msg":"ready to serve client requests"}
Oct 2 18:47:08.030277 systemd[1]: Started etcd-member.service.
Oct 2 18:47:08.030869 systemd[1]: Reached target multi-user.target.
Oct 2 18:47:08.031926 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:08.029Z","caller":"etcdmain/main.go:47","msg":"notifying init daemon"}
Oct 2 18:47:08.031926 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:08.030Z","caller":"etcdmain/main.go:53","msg":"successfully notified init daemon"}
Oct 2 18:47:08.033730 systemd[1]: Starting systemd-update-utmp-runlevel.service...
Oct 2 18:47:08.036702 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:08.036Z","caller":"embed/serve.go:140","msg":"serving client traffic insecurely; this is strongly discouraged!","address":"[::]:2379"}
Oct 2 18:47:08.045255 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:08.044Z","caller":"membership/cluster.go:531","msg":"set initial cluster version","cluster-id":"7ed2db375b9debe2","local-member-id":"7ca6f47e81dfb10f","cluster-version":"3.5"}
Oct 2 18:47:08.045255 etcd-wrapper[2032]: {"level":"info","ts":"2023-10-02T18:47:08.044Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
Oct 2 18:47:08.059835 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully.
Oct 2 18:47:08.060285 systemd[1]: Finished systemd-update-utmp-runlevel.service.
Oct 2 18:47:08.060691 systemd[1]: Startup finished in 1.320s (kernel) + 8.491s (initrd) + 21.963s (userspace) = 31.775s.
Oct 2 18:47:17.486833 systemd[1]: Started sshd@3-172.31.20.179:22-139.178.89.65:54038.service.
Oct 2 18:47:17.667119 sshd[2175]: Accepted publickey for core from 139.178.89.65 port 54038 ssh2: RSA SHA256:ePkK8jKoGlhN3AxcTQ2G+RQZHD5kDZhw675IJmRySH8
Oct 2 18:47:17.670902 sshd[2175]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
Oct 2 18:47:17.679480 systemd[1]: Started session-4.scope.
Oct 2 18:47:17.680345 systemd-logind[1713]: New session 4 of user core.
Oct 2 18:47:17.825020 sshd[2175]: pam_unix(sshd:session): session closed for user core
Oct 2 18:47:17.830944 systemd-logind[1713]: Session 4 logged out. Waiting for processes to exit.
Oct 2 18:47:17.831520 systemd[1]: sshd@3-172.31.20.179:22-139.178.89.65:54038.service: Deactivated successfully.
Oct 2 18:47:17.832741 systemd[1]: session-4.scope: Deactivated successfully.
Oct 2 18:47:17.834269 systemd-logind[1713]: Removed session 4.
Oct 2 18:47:17.855284 systemd[1]: Started sshd@4-172.31.20.179:22-139.178.89.65:54050.service.
Oct 2 18:47:18.034031 sshd[2181]: Accepted publickey for core from 139.178.89.65 port 54050 ssh2: RSA SHA256:ePkK8jKoGlhN3AxcTQ2G+RQZHD5kDZhw675IJmRySH8
Oct 2 18:47:18.037770 sshd[2181]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
Oct 2 18:47:18.046503 systemd[1]: Started session-5.scope.
Oct 2 18:47:18.047465 systemd-logind[1713]: New session 5 of user core.
Oct 2 18:47:18.179112 sshd[2181]: pam_unix(sshd:session): session closed for user core
Oct 2 18:47:18.185044 systemd[1]: sshd@4-172.31.20.179:22-139.178.89.65:54050.service: Deactivated successfully.
Oct 2 18:47:18.186282 systemd[1]: session-5.scope: Deactivated successfully.
Oct 2 18:47:18.187357 systemd-logind[1713]: Session 5 logged out. Waiting for processes to exit.
Oct 2 18:47:18.188830 systemd-logind[1713]: Removed session 5.
Oct 2 18:47:18.211247 systemd[1]: Started sshd@5-172.31.20.179:22-139.178.89.65:54056.service.
Oct 2 18:47:18.395833 sshd[2187]: Accepted publickey for core from 139.178.89.65 port 54056 ssh2: RSA SHA256:ePkK8jKoGlhN3AxcTQ2G+RQZHD5kDZhw675IJmRySH8
Oct 2 18:47:18.399036 sshd[2187]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
Oct 2 18:47:18.407356 systemd-logind[1713]: New session 6 of user core.
Oct 2 18:47:18.408251 systemd[1]: Started session-6.scope.
Oct 2 18:47:18.555693 sshd[2187]: pam_unix(sshd:session): session closed for user core
Oct 2 18:47:18.562605 systemd-logind[1713]: Session 6 logged out. Waiting for processes to exit.
Oct 2 18:47:18.564129 systemd[1]: sshd@5-172.31.20.179:22-139.178.89.65:54056.service: Deactivated successfully.
Oct 2 18:47:18.565390 systemd[1]: session-6.scope: Deactivated successfully.
Oct 2 18:47:18.566874 systemd-logind[1713]: Removed session 6.
Oct 2 18:47:18.586997 systemd[1]: Started sshd@6-172.31.20.179:22-139.178.89.65:54064.service.
Oct 2 18:47:18.771210 sshd[2193]: Accepted publickey for core from 139.178.89.65 port 54064 ssh2: RSA SHA256:ePkK8jKoGlhN3AxcTQ2G+RQZHD5kDZhw675IJmRySH8
Oct 2 18:47:18.774896 sshd[2193]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
Oct 2 18:47:18.782641 systemd-logind[1713]: New session 7 of user core.
Oct 2 18:47:18.783506 systemd[1]: Started session-7.scope.
Oct 2 18:47:18.912869 sudo[2196]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1
Oct 2 18:47:18.913366 sudo[2196]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500)
Oct 2 18:47:18.926800 dbus-daemon[1706]: avc: received setenforce notice (enforcing=1)
Oct 2 18:47:18.930500 sudo[2196]: pam_unix(sudo:session): session closed for user root
Oct 2 18:47:18.955806 sshd[2193]: pam_unix(sshd:session): session closed for user core
Oct 2 18:47:18.961759 systemd-logind[1713]: Session 7 logged out. Waiting for processes to exit.
Oct 2 18:47:18.962393 systemd[1]: sshd@6-172.31.20.179:22-139.178.89.65:54064.service: Deactivated successfully.
Oct 2 18:47:18.963709 systemd[1]: session-7.scope: Deactivated successfully.
Oct 2 18:47:18.965224 systemd-logind[1713]: Removed session 7.
Oct 2 18:47:18.985534 systemd[1]: Started sshd@7-172.31.20.179:22-139.178.89.65:54080.service.
Oct 2 18:47:19.168005 sshd[2200]: Accepted publickey for core from 139.178.89.65 port 54080 ssh2: RSA SHA256:ePkK8jKoGlhN3AxcTQ2G+RQZHD5kDZhw675IJmRySH8
Oct 2 18:47:19.170638 sshd[2200]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
Oct 2 18:47:19.178822 systemd-logind[1713]: New session 8 of user core.
Oct 2 18:47:19.179722 systemd[1]: Started session-8.scope.
Oct 2 18:47:19.300454 sudo[2204]: 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 18:47:19.300986 sudo[2204]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500)
Oct 2 18:47:19.308185 sudo[2204]: pam_unix(sudo:session): session closed for user root
Oct 2 18:47:19.321684 sudo[2203]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/systemctl restart audit-rules
Oct 2 18:47:19.322683 sudo[2203]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500)
Oct 2 18:47:19.346386 systemd[1]: Stopping audit-rules.service...
Oct 2 18:47:19.351000 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 18:47:19.353716 kernel: kauditd_printk_skb: 75 callbacks suppressed
Oct 2 18:47:19.353794 kernel: audit: type=1305 audit(1696272439.351:166): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1
Oct 2 18:47:19.356730 auditctl[2207]: No rules
Oct 2 18:47:19.351000 audit[2207]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffc68fd750 a2=420 a3=0 items=0 ppid=1 pid=2207 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 18:47:19.359664 systemd[1]: audit-rules.service: Deactivated successfully.
Oct 2 18:47:19.371918 kernel: audit: type=1300 audit(1696272439.351:166): arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffc68fd750 a2=420 a3=0 items=0 ppid=1 pid=2207 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 18:47:19.360052 systemd[1]: Stopped audit-rules.service.
Oct 2 18:47:19.366183 systemd[1]: Starting audit-rules.service...
Oct 2 18:47:19.376119 kernel: audit: type=1327 audit(1696272439.351:166): proctitle=2F7362696E2F617564697463746C002D44
Oct 2 18:47:19.351000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D44
Oct 2 18:47:19.359000 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 18:47:19.384401 kernel: audit: type=1131 audit(1696272439.359:167): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=audit-rules comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 2 18:47:19.431512 augenrules[2224]: No rules
Oct 2 18:47:19.433874 systemd[1]: Finished audit-rules.service.
Oct 2 18:47:19.433000 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 18:47:19.436979 sudo[2203]: pam_unix(sudo:session): session closed for user root
Oct 2 18:47:19.435000 audit[2203]: USER_END pid=2203 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 18:47:19.453587 kernel: audit: type=1130 audit(1696272439.433:168): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=audit-rules comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 2 18:47:19.453676 kernel: audit: type=1106 audit(1696272439.435:169): pid=2203 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 18:47:19.453720 kernel: audit: type=1104 audit(1696272439.435:170): pid=2203 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 18:47:19.435000 audit[2203]: CRED_DISP pid=2203 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 18:47:19.465887 sshd[2200]: pam_unix(sshd:session): session closed for user core
Oct 2 18:47:19.467000 audit[2200]: USER_END pid=2200 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 18:47:19.467000 audit[2200]: CRED_DISP pid=2200 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 18:47:19.490022 kernel: audit: type=1106 audit(1696272439.467:171): pid=2200 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 18:47:19.490161 kernel: audit: type=1104 audit(1696272439.467:172): pid=2200 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 18:47:19.482766 systemd-logind[1713]: Session 8 logged out. Waiting for processes to exit.
Oct 2 18:47:19.484119 systemd[1]: sshd@7-172.31.20.179:22-139.178.89.65:54080.service: Deactivated successfully.
Oct 2 18:47:19.485253 systemd[1]: session-8.scope: Deactivated successfully.
Oct 2 18:47:19.487051 systemd-logind[1713]: Removed session 8.
Oct 2 18:47:19.480000 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.20.179:22-139.178.89.65:54080 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 2 18:47:19.499632 kernel: audit: type=1131 audit(1696272439.480:173): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@7-172.31.20.179:22-139.178.89.65:54080 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 2 18:47:19.755000 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.20.179:22-139.178.89.65:54090 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 2 18:47:19.757231 systemd[1]: Started sshd@8-172.31.20.179:22-139.178.89.65:54090.service.
Oct 2 18:47:19.935000 audit[2230]: USER_ACCT pid=2230 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 18:47:19.937788 sshd[2230]: Accepted publickey for core from 139.178.89.65 port 54090 ssh2: RSA SHA256:ePkK8jKoGlhN3AxcTQ2G+RQZHD5kDZhw675IJmRySH8
Oct 2 18:47:19.938000 audit[2230]: CRED_ACQ pid=2230 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 18:47:19.939000 audit[2230]: SYSCALL arch=c00000b7 syscall=64 success=yes exit=3 a0=5 a1=ffffd536d7d0 a2=3 a3=1 items=0 ppid=1 pid=2230 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 18:47:19.939000 audit: PROCTITLE proctitle=737368643A20636F7265205B707269765D
Oct 2 18:47:19.941682 sshd[2230]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
Oct 2 18:47:19.950620 systemd[1]: Started session-9.scope.
Oct 2 18:47:19.950659 systemd-logind[1713]: New session 9 of user core.
Oct 2 18:47:19.959000 audit[2230]: USER_START pid=2230 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 18:47:19.965000 audit[2232]: CRED_ACQ pid=2232 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 18:47:20.226102 sshd[2230]: pam_unix(sshd:session): session closed for user core
Oct 2 18:47:20.226000 audit[2230]: USER_END pid=2230 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 18:47:20.227000 audit[2230]: CRED_DISP pid=2230 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 18:47:20.231918 systemd[1]: session-9.scope: Deactivated successfully.
Oct 2 18:47:20.231946 systemd-logind[1713]: Session 9 logged out. Waiting for processes to exit.
Oct 2 18:47:20.233049 systemd[1]: sshd@8-172.31.20.179:22-139.178.89.65:54090.service: Deactivated successfully.
Oct 2 18:47:20.231000 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.20.179:22-139.178.89.65:54090 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 2 18:47:20.235075 systemd-logind[1713]: Removed session 9.