Oct 2 18:43:26.756396 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Oct 2 18:43:26.756415 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:43:26.756423 kernel: efi: EFI v2.70 by EDK II Oct 2 18:43:26.756428 kernel: efi: SMBIOS 3.0=0xd9260000 ACPI 2.0=0xd9240000 MEMATTR=0xda32b018 RNG=0xd9220018 MEMRESERVE=0xd9521c18 Oct 2 18:43:26.756434 kernel: random: crng init done Oct 2 18:43:26.756439 kernel: ACPI: Early table checksum verification disabled Oct 2 18:43:26.756445 kernel: ACPI: RSDP 0x00000000D9240000 000024 (v02 BOCHS ) Oct 2 18:43:26.756452 kernel: ACPI: XSDT 0x00000000D9230000 000064 (v01 BOCHS BXPC 00000001 01000013) Oct 2 18:43:26.756458 kernel: ACPI: FACP 0x00000000D91E0000 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Oct 2 18:43:26.756463 kernel: ACPI: DSDT 0x00000000D91F0000 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 2 18:43:26.756469 kernel: ACPI: APIC 0x00000000D91D0000 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Oct 2 18:43:26.756474 kernel: ACPI: PPTT 0x00000000D91C0000 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 2 18:43:26.756480 kernel: ACPI: GTDT 0x00000000D91B0000 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 2 18:43:26.756485 kernel: ACPI: MCFG 0x00000000D91A0000 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 2 18:43:26.756493 kernel: ACPI: SPCR 0x00000000D9190000 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 2 18:43:26.756499 kernel: ACPI: DBG2 0x00000000D9180000 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Oct 2 18:43:26.756505 kernel: ACPI: IORT 0x00000000D9170000 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 2 18:43:26.756511 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Oct 2 18:43:26.756517 kernel: NUMA: Failed to initialise from firmware Oct 2 18:43:26.756523 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Oct 2 18:43:26.756529 kernel: NUMA: NODE_DATA [mem 0xdcb0b900-0xdcb10fff] Oct 2 18:43:26.756534 kernel: Zone ranges: Oct 2 18:43:26.756540 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Oct 2 18:43:26.756547 kernel: DMA32 empty Oct 2 18:43:26.756553 kernel: Normal empty Oct 2 18:43:26.756558 kernel: Movable zone start for each node Oct 2 18:43:26.756564 kernel: Early memory node ranges Oct 2 18:43:26.756570 kernel: node 0: [mem 0x0000000040000000-0x00000000d924ffff] Oct 2 18:43:26.756575 kernel: node 0: [mem 0x00000000d9250000-0x00000000d951ffff] Oct 2 18:43:26.756581 kernel: node 0: [mem 0x00000000d9520000-0x00000000dc7fffff] Oct 2 18:43:26.756587 kernel: node 0: [mem 0x00000000dc800000-0x00000000dc88ffff] Oct 2 18:43:26.756592 kernel: node 0: [mem 0x00000000dc890000-0x00000000dc89ffff] Oct 2 18:43:26.756598 kernel: node 0: [mem 0x00000000dc8a0000-0x00000000dc9bffff] Oct 2 18:43:26.756604 kernel: node 0: [mem 0x00000000dc9c0000-0x00000000dcffffff] Oct 2 18:43:26.756610 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Oct 2 18:43:26.756617 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Oct 2 18:43:26.756622 kernel: psci: probing for conduit method from ACPI. Oct 2 18:43:26.756628 kernel: psci: PSCIv1.1 detected in firmware. Oct 2 18:43:26.756634 kernel: psci: Using standard PSCI v0.2 function IDs Oct 2 18:43:26.756640 kernel: psci: Trusted OS migration not required Oct 2 18:43:26.756648 kernel: psci: SMC Calling Convention v1.1 Oct 2 18:43:26.756654 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Oct 2 18:43:26.756662 kernel: ACPI: SRAT not present Oct 2 18:43:26.756668 kernel: percpu: Embedded 29 pages/cpu s79960 r8192 d30632 u118784 Oct 2 18:43:26.756674 kernel: pcpu-alloc: s79960 r8192 d30632 u118784 alloc=29*4096 Oct 2 18:43:26.756681 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 2 18:43:26.756687 kernel: Detected PIPT I-cache on CPU0 Oct 2 18:43:26.756693 kernel: CPU features: detected: GIC system register CPU interface Oct 2 18:43:26.756699 kernel: CPU features: detected: Hardware dirty bit management Oct 2 18:43:26.756705 kernel: CPU features: detected: Spectre-v4 Oct 2 18:43:26.756711 kernel: CPU features: detected: Spectre-BHB Oct 2 18:43:26.756718 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 2 18:43:26.756725 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 2 18:43:26.756731 kernel: CPU features: detected: ARM erratum 1418040 Oct 2 18:43:26.756737 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Oct 2 18:43:26.756743 kernel: Policy zone: DMA Oct 2 18:43:26.756750 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=ttyS0,115200 flatcar.first_boot=detected acpi=force verity.usrhash=684fe6a2259d7fb96810743ab87aaaa03d9f185b113bd6990a64d1079e5672ca Oct 2 18:43:26.756757 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Oct 2 18:43:26.756763 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 2 18:43:26.756769 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 2 18:43:26.756775 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 2 18:43:26.756782 kernel: Memory: 2459280K/2572288K available (9792K kernel code, 2092K rwdata, 7548K rodata, 34560K init, 779K bss, 113008K reserved, 0K cma-reserved) Oct 2 18:43:26.756789 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 2 18:43:26.756795 kernel: trace event string verifier disabled Oct 2 18:43:26.756801 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 2 18:43:26.756808 kernel: rcu: RCU event tracing is enabled. Oct 2 18:43:26.756814 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 2 18:43:26.756820 kernel: Trampoline variant of Tasks RCU enabled. Oct 2 18:43:26.756827 kernel: Tracing variant of Tasks RCU enabled. Oct 2 18:43:26.756833 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 2 18:43:26.756839 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 2 18:43:26.756845 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 2 18:43:26.756852 kernel: GICv3: 256 SPIs implemented Oct 2 18:43:26.756859 kernel: GICv3: 0 Extended SPIs implemented Oct 2 18:43:26.756902 kernel: GICv3: Distributor has no Range Selector support Oct 2 18:43:26.756908 kernel: Root IRQ handler: gic_handle_irq Oct 2 18:43:26.756914 kernel: GICv3: 16 PPIs implemented Oct 2 18:43:26.756920 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Oct 2 18:43:26.756927 kernel: ACPI: SRAT not present Oct 2 18:43:26.756935 kernel: ITS [mem 0x08080000-0x0809ffff] Oct 2 18:43:26.756943 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Oct 2 18:43:26.756950 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Oct 2 18:43:26.756956 kernel: GICv3: using LPI property table @0x00000000400d0000 Oct 2 18:43:26.756962 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Oct 2 18:43:26.756969 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 2 18:43:26.756979 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Oct 2 18:43:26.756986 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Oct 2 18:43:26.756992 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Oct 2 18:43:26.756999 kernel: arm-pv: using stolen time PV Oct 2 18:43:26.757005 kernel: Console: colour dummy device 80x25 Oct 2 18:43:26.757012 kernel: ACPI: Core revision 20210730 Oct 2 18:43:26.757018 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Oct 2 18:43:26.757025 kernel: pid_max: default: 32768 minimum: 301 Oct 2 18:43:26.757031 kernel: LSM: Security Framework initializing Oct 2 18:43:26.757037 kernel: SELinux: Initializing. Oct 2 18:43:26.757045 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 2 18:43:26.757052 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 2 18:43:26.757058 kernel: rcu: Hierarchical SRCU implementation. Oct 2 18:43:26.757064 kernel: Platform MSI: ITS@0x8080000 domain created Oct 2 18:43:26.757071 kernel: PCI/MSI: ITS@0x8080000 domain created Oct 2 18:43:26.757077 kernel: Remapping and enabling EFI services. Oct 2 18:43:26.757084 kernel: smp: Bringing up secondary CPUs ... Oct 2 18:43:26.757090 kernel: Detected PIPT I-cache on CPU1 Oct 2 18:43:26.757096 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Oct 2 18:43:26.757104 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Oct 2 18:43:26.757111 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 2 18:43:26.757117 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Oct 2 18:43:26.757123 kernel: Detected PIPT I-cache on CPU2 Oct 2 18:43:26.757130 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Oct 2 18:43:26.757137 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Oct 2 18:43:26.757143 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 2 18:43:26.757149 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Oct 2 18:43:26.757156 kernel: Detected PIPT I-cache on CPU3 Oct 2 18:43:26.757162 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Oct 2 18:43:26.757170 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Oct 2 18:43:26.757176 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 2 18:43:26.757182 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Oct 2 18:43:26.757189 kernel: smp: Brought up 1 node, 4 CPUs Oct 2 18:43:26.757200 kernel: SMP: Total of 4 processors activated. Oct 2 18:43:26.757207 kernel: CPU features: detected: 32-bit EL0 Support Oct 2 18:43:26.757214 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 2 18:43:26.757221 kernel: CPU features: detected: Common not Private translations Oct 2 18:43:26.757228 kernel: CPU features: detected: CRC32 instructions Oct 2 18:43:26.757234 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Oct 2 18:43:26.757241 kernel: CPU features: detected: LSE atomic instructions Oct 2 18:43:26.757248 kernel: CPU features: detected: Privileged Access Never Oct 2 18:43:26.757256 kernel: CPU features: detected: RAS Extension Support Oct 2 18:43:26.757263 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 2 18:43:26.757276 kernel: CPU: All CPU(s) started at EL1 Oct 2 18:43:26.757283 kernel: alternatives: patching kernel code Oct 2 18:43:26.757292 kernel: devtmpfs: initialized Oct 2 18:43:26.757299 kernel: KASLR enabled Oct 2 18:43:26.757305 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 2 18:43:26.757312 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 2 18:43:26.757319 kernel: pinctrl core: initialized pinctrl subsystem Oct 2 18:43:26.757326 kernel: SMBIOS 3.0.0 present. Oct 2 18:43:26.757333 kernel: DMI: QEMU KVM Virtual Machine, BIOS 0.0.0 02/06/2015 Oct 2 18:43:26.757339 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Oct 2 18:43:26.757346 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 2 18:43:26.757353 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 2 18:43:26.757361 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 2 18:43:26.757368 kernel: audit: initializing netlink subsys (disabled) Oct 2 18:43:26.757375 kernel: audit: type=2000 audit(0.031:1): state=initialized audit_enabled=0 res=1 Oct 2 18:43:26.757381 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 2 18:43:26.757388 kernel: cpuidle: using governor menu Oct 2 18:43:26.757394 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 2 18:43:26.757401 kernel: ASID allocator initialised with 32768 entries Oct 2 18:43:26.757408 kernel: ACPI: bus type PCI registered Oct 2 18:43:26.757414 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 2 18:43:26.757422 kernel: Serial: AMBA PL011 UART driver Oct 2 18:43:26.757429 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Oct 2 18:43:26.757436 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Oct 2 18:43:26.757442 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Oct 2 18:43:26.757449 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Oct 2 18:43:26.757456 kernel: cryptd: max_cpu_qlen set to 1000 Oct 2 18:43:26.757463 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Oct 2 18:43:26.757469 kernel: ACPI: Added _OSI(Module Device) Oct 2 18:43:26.757476 kernel: ACPI: Added _OSI(Processor Device) Oct 2 18:43:26.757484 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Oct 2 18:43:26.757491 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 2 18:43:26.757497 kernel: ACPI: Added _OSI(Linux-Dell-Video) Oct 2 18:43:26.757504 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Oct 2 18:43:26.757511 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Oct 2 18:43:26.757518 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 2 18:43:26.757524 kernel: ACPI: Interpreter enabled Oct 2 18:43:26.757531 kernel: ACPI: Using GIC for interrupt routing Oct 2 18:43:26.757538 kernel: ACPI: MCFG table detected, 1 entries Oct 2 18:43:26.757546 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Oct 2 18:43:26.757553 kernel: printk: console [ttyAMA0] enabled Oct 2 18:43:26.757560 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 2 18:43:26.757693 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 2 18:43:26.757757 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 2 18:43:26.757817 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 2 18:43:26.757875 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Oct 2 18:43:26.757947 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Oct 2 18:43:26.757957 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Oct 2 18:43:26.757964 kernel: PCI host bridge to bus 0000:00 Oct 2 18:43:26.758034 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Oct 2 18:43:26.758089 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 2 18:43:26.758142 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Oct 2 18:43:26.758194 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 2 18:43:26.758275 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Oct 2 18:43:26.758351 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Oct 2 18:43:26.758413 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Oct 2 18:43:26.758473 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Oct 2 18:43:26.758534 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Oct 2 18:43:26.758594 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Oct 2 18:43:26.758657 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Oct 2 18:43:26.758721 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Oct 2 18:43:26.758775 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Oct 2 18:43:26.758836 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 2 18:43:26.758900 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Oct 2 18:43:26.758910 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Oct 2 18:43:26.758917 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Oct 2 18:43:26.758924 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Oct 2 18:43:26.758932 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Oct 2 18:43:26.758939 kernel: iommu: Default domain type: Translated Oct 2 18:43:26.758946 kernel: iommu: DMA domain TLB invalidation policy: strict mode Oct 2 18:43:26.758953 kernel: vgaarb: loaded Oct 2 18:43:26.758959 kernel: pps_core: LinuxPPS API ver. 1 registered Oct 2 18:43:26.758966 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Oct 2 18:43:26.758973 kernel: PTP clock support registered Oct 2 18:43:26.758980 kernel: Registered efivars operations Oct 2 18:43:26.758987 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 2 18:43:26.758993 kernel: VFS: Disk quotas dquot_6.6.0 Oct 2 18:43:26.759002 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 2 18:43:26.759008 kernel: pnp: PnP ACPI init Oct 2 18:43:26.759075 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Oct 2 18:43:26.759085 kernel: pnp: PnP ACPI: found 1 devices Oct 2 18:43:26.759091 kernel: NET: Registered PF_INET protocol family Oct 2 18:43:26.759098 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 2 18:43:26.759105 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 2 18:43:26.759112 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 2 18:43:26.759120 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 2 18:43:26.759127 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Oct 2 18:43:26.759134 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 2 18:43:26.759141 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 2 18:43:26.759147 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 2 18:43:26.759154 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Oct 2 18:43:26.759161 kernel: PCI: CLS 0 bytes, default 64 Oct 2 18:43:26.759168 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Oct 2 18:43:26.759175 kernel: kvm [1]: HYP mode not available Oct 2 18:43:26.759182 kernel: Initialise system trusted keyrings Oct 2 18:43:26.759189 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 2 18:43:26.759196 kernel: Key type asymmetric registered Oct 2 18:43:26.759202 kernel: Asymmetric key parser 'x509' registered Oct 2 18:43:26.759209 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Oct 2 18:43:26.759216 kernel: io scheduler mq-deadline registered Oct 2 18:43:26.759223 kernel: io scheduler kyber registered Oct 2 18:43:26.759229 kernel: io scheduler bfq registered Oct 2 18:43:26.759236 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 2 18:43:26.759244 kernel: ACPI: button: Power Button [PWRB] Oct 2 18:43:26.759251 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Oct 2 18:43:26.759327 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Oct 2 18:43:26.759337 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 2 18:43:26.759344 kernel: thunder_xcv, ver 1.0 Oct 2 18:43:26.759351 kernel: thunder_bgx, ver 1.0 Oct 2 18:43:26.759358 kernel: nicpf, ver 1.0 Oct 2 18:43:26.759364 kernel: nicvf, ver 1.0 Oct 2 18:43:26.759444 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 2 18:43:26.759505 kernel: rtc-efi rtc-efi.0: setting system clock to 2023-10-02T18:43:26 UTC (1696272206) Oct 2 18:43:26.759514 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 2 18:43:26.759522 kernel: NET: Registered PF_INET6 protocol family Oct 2 18:43:26.759529 kernel: Segment Routing with IPv6 Oct 2 18:43:26.759535 kernel: In-situ OAM (IOAM) with IPv6 Oct 2 18:43:26.759542 kernel: NET: Registered PF_PACKET protocol family Oct 2 18:43:26.759548 kernel: Key type dns_resolver registered Oct 2 18:43:26.759555 kernel: registered taskstats version 1 Oct 2 18:43:26.759563 kernel: Loading compiled-in X.509 certificates Oct 2 18:43:26.759570 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.15.132-flatcar: 3a2a38edc68cb70dc60ec0223a6460557b3bb28d' Oct 2 18:43:26.759577 kernel: Key type .fscrypt registered Oct 2 18:43:26.759584 kernel: Key type fscrypt-provisioning registered Oct 2 18:43:26.759590 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 2 18:43:26.759597 kernel: ima: Allocated hash algorithm: sha1 Oct 2 18:43:26.759604 kernel: ima: No architecture policies found Oct 2 18:43:26.759610 kernel: Freeing unused kernel memory: 34560K Oct 2 18:43:26.759617 kernel: Run /init as init process Oct 2 18:43:26.759625 kernel: with arguments: Oct 2 18:43:26.759631 kernel: /init Oct 2 18:43:26.759638 kernel: with environment: Oct 2 18:43:26.759644 kernel: HOME=/ Oct 2 18:43:26.759651 kernel: TERM=linux Oct 2 18:43:26.759657 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 2 18:43:26.759666 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:43:26.759675 systemd[1]: Detected virtualization kvm. Oct 2 18:43:26.759684 systemd[1]: Detected architecture arm64. Oct 2 18:43:26.759691 systemd[1]: Running in initrd. Oct 2 18:43:26.759698 systemd[1]: No hostname configured, using default hostname. Oct 2 18:43:26.759705 systemd[1]: Hostname set to . Oct 2 18:43:26.759713 systemd[1]: Initializing machine ID from VM UUID. Oct 2 18:43:26.759720 systemd[1]: Queued start job for default target initrd.target. Oct 2 18:43:26.759727 systemd[1]: Started systemd-ask-password-console.path. Oct 2 18:43:26.759734 systemd[1]: Reached target cryptsetup.target. Oct 2 18:43:26.759742 systemd[1]: Reached target paths.target. Oct 2 18:43:26.759750 systemd[1]: Reached target slices.target. Oct 2 18:43:26.759757 systemd[1]: Reached target swap.target. Oct 2 18:43:26.759764 systemd[1]: Reached target timers.target. Oct 2 18:43:26.759771 systemd[1]: Listening on iscsid.socket. Oct 2 18:43:26.759778 systemd[1]: Listening on iscsiuio.socket. Oct 2 18:43:26.759786 systemd[1]: Listening on systemd-journald-audit.socket. Oct 2 18:43:26.759794 systemd[1]: Listening on systemd-journald-dev-log.socket. Oct 2 18:43:26.759802 systemd[1]: Listening on systemd-journald.socket. Oct 2 18:43:26.759809 systemd[1]: Listening on systemd-networkd.socket. Oct 2 18:43:26.759816 systemd[1]: Listening on systemd-udevd-control.socket. Oct 2 18:43:26.759823 systemd[1]: Listening on systemd-udevd-kernel.socket. Oct 2 18:43:26.759830 systemd[1]: Reached target sockets.target. Oct 2 18:43:26.759838 systemd[1]: Starting kmod-static-nodes.service... Oct 2 18:43:26.759845 systemd[1]: Finished network-cleanup.service. Oct 2 18:43:26.759852 systemd[1]: Starting systemd-fsck-usr.service... Oct 2 18:43:26.759860 systemd[1]: Starting systemd-journald.service... Oct 2 18:43:26.759868 systemd[1]: Starting systemd-modules-load.service... Oct 2 18:43:26.759875 systemd[1]: Starting systemd-resolved.service... Oct 2 18:43:26.759882 systemd[1]: Starting systemd-vconsole-setup.service... Oct 2 18:43:26.759897 systemd[1]: Finished kmod-static-nodes.service. Oct 2 18:43:26.759905 systemd[1]: Finished systemd-fsck-usr.service. Oct 2 18:43:26.759912 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... Oct 2 18:43:26.759922 systemd[1]: Finished systemd-vconsole-setup.service. Oct 2 18:43:26.759931 kernel: audit: type=1130 audit(1696272206.756:2): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 18:43:26.759940 systemd[1]: Starting dracut-cmdline-ask.service... Oct 2 18:43:26.759951 systemd-journald[290]: Journal started Oct 2 18:43:26.759992 systemd-journald[290]: Runtime Journal (/run/log/journal/11754ca7bb294dd7a8fb562d7bd617fc) is 6.0M, max 48.7M, 42.6M free. Oct 2 18:43:26.756000 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:43:26.748072 systemd-modules-load[291]: Inserted module 'overlay' Oct 2 18:43:26.761282 systemd[1]: Started systemd-journald.service. Oct 2 18:43:26.761924 kernel: audit: type=1130 audit(1696272206.761:3): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 18:43:26.761000 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:43:26.763501 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. Oct 2 18:43:26.766735 kernel: audit: type=1130 audit(1696272206.764:4): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 18:43:26.764000 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:43:26.771759 systemd-resolved[292]: Positive Trust Anchors: Oct 2 18:43:26.772740 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:43:26.771770 systemd-resolved[292]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 2 18:43:26.771797 systemd-resolved[292]: 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:43:26.776857 systemd-resolved[292]: Defaulting to hostname 'linux'. Oct 2 18:43:26.777574 systemd-modules-load[291]: Inserted module 'br_netfilter' Oct 2 18:43:26.780941 kernel: Bridge firewalling registered Oct 2 18:43:26.780961 kernel: audit: type=1130 audit(1696272206.778:5): 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:43:26.778000 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:43:26.777719 systemd[1]: Started systemd-resolved.service. Oct 2 18:43:26.778711 systemd[1]: Reached target nss-lookup.target. Oct 2 18:43:26.783751 systemd[1]: Finished dracut-cmdline-ask.service. Oct 2 18:43:26.784000 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:43:26.787306 kernel: audit: type=1130 audit(1696272206.784:6): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 18:43:26.787500 systemd[1]: Starting dracut-cmdline.service... Oct 2 18:43:26.789303 kernel: SCSI subsystem initialized Oct 2 18:43:26.796344 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Oct 2 18:43:26.796388 kernel: device-mapper: uevent: version 1.0.3 Oct 2 18:43:26.796399 kernel: device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com Oct 2 18:43:26.798636 systemd-modules-load[291]: Inserted module 'dm_multipath' Oct 2 18:43:26.799423 systemd[1]: Finished systemd-modules-load.service. Oct 2 18:43:26.800755 systemd[1]: Starting systemd-sysctl.service... Oct 2 18:43:26.799000 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:43:26.803849 dracut-cmdline[308]: dracut-dracut-053 Oct 2 18:43:26.804658 kernel: audit: type=1130 audit(1696272206.799:7): 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:43:26.806563 dracut-cmdline[308]: 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=ttyS0,115200 flatcar.first_boot=detected acpi=force verity.usrhash=684fe6a2259d7fb96810743ab87aaaa03d9f185b113bd6990a64d1079e5672ca Oct 2 18:43:26.811000 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:43:26.810829 systemd[1]: Finished systemd-sysctl.service. Oct 2 18:43:26.813938 kernel: audit: type=1130 audit(1696272206.811:8): 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:43:26.873294 kernel: Loading iSCSI transport class v2.0-870. Oct 2 18:43:26.881296 kernel: iscsi: registered transport (tcp) Oct 2 18:43:26.894286 kernel: iscsi: registered transport (qla4xxx) Oct 2 18:43:26.894311 kernel: QLogic iSCSI HBA Driver Oct 2 18:43:26.938819 systemd[1]: Finished dracut-cmdline.service. Oct 2 18:43:26.939000 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:43:26.940217 systemd[1]: Starting dracut-pre-udev.service... Oct 2 18:43:26.942331 kernel: audit: type=1130 audit(1696272206.939: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:43:26.985295 kernel: raid6: neonx8 gen() 13564 MB/s Oct 2 18:43:27.002311 kernel: raid6: neonx8 xor() 10787 MB/s Oct 2 18:43:27.019291 kernel: raid6: neonx4 gen() 13456 MB/s Oct 2 18:43:27.036311 kernel: raid6: neonx4 xor() 11239 MB/s Oct 2 18:43:27.053286 kernel: raid6: neonx2 gen() 12824 MB/s Oct 2 18:43:27.070287 kernel: raid6: neonx2 xor() 10136 MB/s Oct 2 18:43:27.087288 kernel: raid6: neonx1 gen() 10491 MB/s Oct 2 18:43:27.104289 kernel: raid6: neonx1 xor() 8737 MB/s Oct 2 18:43:27.121293 kernel: raid6: int64x8 gen() 6227 MB/s Oct 2 18:43:27.138289 kernel: raid6: int64x8 xor() 3528 MB/s Oct 2 18:43:27.155287 kernel: raid6: int64x4 gen() 7199 MB/s Oct 2 18:43:27.172295 kernel: raid6: int64x4 xor() 3836 MB/s Oct 2 18:43:27.189297 kernel: raid6: int64x2 gen() 6131 MB/s Oct 2 18:43:27.206284 kernel: raid6: int64x2 xor() 3293 MB/s Oct 2 18:43:27.223293 kernel: raid6: int64x1 gen() 5024 MB/s Oct 2 18:43:27.240394 kernel: raid6: int64x1 xor() 2638 MB/s Oct 2 18:43:27.240427 kernel: raid6: using algorithm neonx8 gen() 13564 MB/s Oct 2 18:43:27.240437 kernel: raid6: .... xor() 10787 MB/s, rmw enabled Oct 2 18:43:27.240446 kernel: raid6: using neon recovery algorithm Oct 2 18:43:27.251536 kernel: xor: measuring software checksum speed Oct 2 18:43:27.251558 kernel: 8regs : 17224 MB/sec Oct 2 18:43:27.252375 kernel: 32regs : 20755 MB/sec Oct 2 18:43:27.253529 kernel: arm64_neon : 27807 MB/sec Oct 2 18:43:27.253541 kernel: xor: using function: arm64_neon (27807 MB/sec) Oct 2 18:43:27.316304 kernel: Btrfs loaded, crc32c=crc32c-generic, zoned=no, fsverity=no Oct 2 18:43:27.327791 systemd[1]: Finished dracut-pre-udev.service. Oct 2 18:43:27.328000 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:43:27.328000 audit: BPF prog-id=7 op=LOAD Oct 2 18:43:27.330000 audit: BPF prog-id=8 op=LOAD Oct 2 18:43:27.331105 systemd[1]: Starting systemd-udevd.service... Oct 2 18:43:27.332321 kernel: audit: type=1130 audit(1696272207.328: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:43:27.345371 systemd-udevd[491]: Using default interface naming scheme 'v252'. Oct 2 18:43:27.348644 systemd[1]: Started systemd-udevd.service. Oct 2 18:43:27.348000 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:43:27.351736 systemd[1]: Starting dracut-pre-trigger.service... Oct 2 18:43:27.367430 dracut-pre-trigger[498]: rd.md=0: removing MD RAID activation Oct 2 18:43:27.404449 systemd[1]: Finished dracut-pre-trigger.service. Oct 2 18:43:27.404000 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:43:27.408406 systemd[1]: Starting systemd-udev-trigger.service... Oct 2 18:43:27.445183 systemd[1]: Finished systemd-udev-trigger.service. Oct 2 18:43:27.457000 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:43:27.502947 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Oct 2 18:43:27.505352 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 2 18:43:27.516293 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by (udev-worker) (551) Oct 2 18:43:27.517328 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device. Oct 2 18:43:27.518050 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device. Oct 2 18:43:27.523824 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device. Oct 2 18:43:27.526914 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device. Oct 2 18:43:27.531862 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Oct 2 18:43:27.533241 systemd[1]: Starting disk-uuid.service... Oct 2 18:43:27.541319 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 2 18:43:28.559291 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 2 18:43:28.559479 disk-uuid[569]: The operation has completed successfully. Oct 2 18:43:28.596801 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 2 18:43:28.597000 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:43:28.597000 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:43:28.596898 systemd[1]: Finished disk-uuid.service. Oct 2 18:43:28.598346 systemd[1]: Starting verity-setup.service... Oct 2 18:43:28.620328 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Oct 2 18:43:28.660435 systemd[1]: Found device dev-mapper-usr.device. Oct 2 18:43:28.662969 systemd[1]: Mounting sysusr-usr.mount... Oct 2 18:43:28.665471 systemd[1]: Finished verity-setup.service. Oct 2 18:43:28.665000 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:43:28.731293 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: norecovery. Quota mode: none. Oct 2 18:43:28.731635 systemd[1]: Mounted sysusr-usr.mount. Oct 2 18:43:28.732264 systemd[1]: afterburn-network-kargs.service was skipped because no trigger condition checks were met. Oct 2 18:43:28.733017 systemd[1]: Starting ignition-setup.service... Oct 2 18:43:28.734632 systemd[1]: Starting parse-ip-for-networkd.service... Oct 2 18:43:28.752429 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Oct 2 18:43:28.752487 kernel: BTRFS info (device vda6): using free space tree Oct 2 18:43:28.753289 kernel: BTRFS info (device vda6): has skinny extents Oct 2 18:43:28.765865 systemd[1]: mnt-oem.mount: Deactivated successfully. Oct 2 18:43:28.778859 systemd[1]: Finished ignition-setup.service. Oct 2 18:43:28.779000 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:43:28.780362 systemd[1]: Starting ignition-fetch-offline.service... Oct 2 18:43:28.854764 systemd[1]: Finished parse-ip-for-networkd.service. Oct 2 18:43:28.855000 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:43:28.856000 audit: BPF prog-id=9 op=LOAD Oct 2 18:43:28.856935 systemd[1]: Starting systemd-networkd.service... Oct 2 18:43:28.877826 ignition[667]: Ignition 2.14.0 Oct 2 18:43:28.877835 ignition[667]: Stage: fetch-offline Oct 2 18:43:28.878369 systemd-networkd[746]: lo: Link UP Oct 2 18:43:28.877874 ignition[667]: no configs at "/usr/lib/ignition/base.d" Oct 2 18:43:28.878372 systemd-networkd[746]: lo: Gained carrier Oct 2 18:43:28.880000 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:43:28.877891 ignition[667]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 2 18:43:28.878720 systemd-networkd[746]: Enumeration completed Oct 2 18:43:28.878049 ignition[667]: parsed url from cmdline: "" Oct 2 18:43:28.878908 systemd-networkd[746]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 2 18:43:28.878053 ignition[667]: no config URL provided Oct 2 18:43:28.880057 systemd-networkd[746]: eth0: Link UP Oct 2 18:43:28.878058 ignition[667]: reading system config file "/usr/lib/ignition/user.ign" Oct 2 18:43:28.880060 systemd-networkd[746]: eth0: Gained carrier Oct 2 18:43:28.878065 ignition[667]: no config at "/usr/lib/ignition/user.ign" Oct 2 18:43:28.880356 systemd[1]: Started systemd-networkd.service. Oct 2 18:43:28.878082 ignition[667]: op(1): [started] loading QEMU firmware config module Oct 2 18:43:28.881186 systemd[1]: Reached target network.target. Oct 2 18:43:28.878086 ignition[667]: op(1): executing: "modprobe" "qemu_fw_cfg" Oct 2 18:43:28.882870 systemd[1]: Starting iscsiuio.service... Oct 2 18:43:28.894451 ignition[667]: op(1): [finished] loading QEMU firmware config module Oct 2 18:43:28.894000 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:43:28.894467 systemd[1]: Started iscsiuio.service. Oct 2 18:43:28.895944 systemd[1]: Starting iscsid.service... Oct 2 18:43:28.899815 ignition[667]: parsing config with SHA512: a254b6c8e6f5566f2d6b05b9e965644af245138eb8bfcfffb251bd56ae0843e27e6ec770553ad1aa0c4d27c07edd37461801a3a157a44a107ca54969f070b896 Oct 2 18:43:28.900064 iscsid[752]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Oct 2 18:43:28.900064 iscsid[752]: iscsid: Warning: InitiatorName file /etc/iscsi/initiatorname.iscsi does not exist or does not contain a properly formatted InitiatorName. If using software iscsi (iscsi_tcp or ib_iser) or partial offload (bnx2i or cxgbi iscsi), you may not be able to log into or discover targets. Please create a file /etc/iscsi/initiatorname.iscsi that contains a sting with the format: InitiatorName=iqn.yyyy-mm.[:identifier]. Oct 2 18:43:28.900064 iscsid[752]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Oct 2 18:43:28.900064 iscsid[752]: If using hardware iscsi like qla4xxx this message can be ignored. Oct 2 18:43:28.904000 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:43:28.908458 iscsid[752]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Oct 2 18:43:28.908458 iscsid[752]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Oct 2 18:43:28.901379 systemd-networkd[746]: eth0: DHCPv4 address 10.0.0.10/16, gateway 10.0.0.1 acquired from 10.0.0.1 Oct 2 18:43:28.904536 systemd[1]: Started iscsid.service. Oct 2 18:43:28.906094 systemd[1]: Starting dracut-initqueue.service... Oct 2 18:43:28.918806 systemd[1]: Finished dracut-initqueue.service. Oct 2 18:43:28.919000 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:43:28.919710 systemd[1]: Reached target remote-fs-pre.target. Oct 2 18:43:28.920555 systemd[1]: Reached target remote-cryptsetup.target. Oct 2 18:43:28.921504 systemd[1]: Reached target remote-fs.target. Oct 2 18:43:28.923328 systemd[1]: Starting dracut-pre-mount.service... Oct 2 18:43:28.930842 unknown[667]: fetched base config from "system" Oct 2 18:43:28.930853 unknown[667]: fetched user config from "qemu" Oct 2 18:43:28.931187 ignition[667]: fetch-offline: fetch-offline passed Oct 2 18:43:28.933000 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:43:28.932809 systemd[1]: Finished ignition-fetch-offline.service. Oct 2 18:43:28.934000 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:43:28.931254 ignition[667]: Ignition finished successfully Oct 2 18:43:28.933987 systemd[1]: Finished dracut-pre-mount.service. Oct 2 18:43:28.935117 systemd[1]: ignition-fetch.service was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Oct 2 18:43:28.935856 systemd[1]: Starting ignition-kargs.service... Oct 2 18:43:28.946265 ignition[767]: Ignition 2.14.0 Oct 2 18:43:28.946304 ignition[767]: Stage: kargs Oct 2 18:43:28.946407 ignition[767]: no configs at "/usr/lib/ignition/base.d" Oct 2 18:43:28.946417 ignition[767]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 2 18:43:28.947165 ignition[767]: kargs: kargs passed Oct 2 18:43:28.947213 ignition[767]: Ignition finished successfully Oct 2 18:43:28.949091 systemd[1]: Finished ignition-kargs.service. Oct 2 18:43:28.949000 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:43:28.950859 systemd[1]: Starting ignition-disks.service... Oct 2 18:43:28.958749 ignition[773]: Ignition 2.14.0 Oct 2 18:43:28.958764 ignition[773]: Stage: disks Oct 2 18:43:28.958855 ignition[773]: no configs at "/usr/lib/ignition/base.d" Oct 2 18:43:28.958864 ignition[773]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 2 18:43:28.959817 ignition[773]: disks: createFilesystems: op(1): [started] waiting for devices [/dev/disk/by-label/OEM] Oct 2 18:43:28.967236 ignition[773]: disks: createFilesystems: op(1): [finished] waiting for devices [/dev/disk/by-label/OEM] Oct 2 18:43:28.967342 ignition[773]: disks: createFilesystems: created device alias for "/dev/disk/by-label/OEM": "/run/ignition/dev_aliases/dev/disk/by-label/OEM" -> "/dev/vda6" Oct 2 18:43:28.967885 ignition[773]: disks: createFilesystems: op(2): [started] determining filesystem type of "/dev/disk/by-label/OEM" Oct 2 18:43:28.988856 ignition[773]: disks: createFilesystems: op(2): [finished] determining filesystem type of "/dev/disk/by-label/OEM" Oct 2 18:43:28.988882 ignition[773]: disks: createFilesystems: found btrfs filesystem at "/dev/disk/by-label/OEM" with uuid "fa933033-92ba-448f-a024-615582890b87" and label "OEM" Oct 2 18:43:28.991417 systemd[1]: Finished ignition-disks.service. Oct 2 18:43:28.992000 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:43:28.988887 ignition[773]: disks: createFilesystems: filesystem at "/dev/disk/by-label/OEM" is already correctly formatted. Skipping mkfs... Oct 2 18:43:28.988904 ignition[773]: disks: disks passed Oct 2 18:43:28.993215 systemd[1]: Reached target initrd-root-device.target. Oct 2 18:43:28.988991 ignition[773]: Ignition finished successfully Oct 2 18:43:28.994718 systemd[1]: Reached target local-fs-pre.target. Oct 2 18:43:28.995232 systemd[1]: Reached target local-fs.target. Oct 2 18:43:28.996077 systemd[1]: Reached target sysinit.target. Oct 2 18:43:28.997019 systemd[1]: Reached target basic.target. Oct 2 18:43:28.998749 systemd[1]: Starting systemd-fsck-root.service... Oct 2 18:43:29.013996 systemd-fsck[781]: ROOT: clean, 603/553520 files, 56011/553472 blocks Oct 2 18:43:29.018000 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:43:29.018535 systemd[1]: Finished systemd-fsck-root.service. Oct 2 18:43:29.020538 systemd[1]: Mounting sysroot.mount... Oct 2 18:43:29.037929 systemd[1]: Mounted sysroot.mount. Oct 2 18:43:29.038827 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none. Oct 2 18:43:29.038523 systemd[1]: Reached target initrd-root-fs.target. Oct 2 18:43:29.042114 systemd[1]: Mounting sysroot-usr.mount... Oct 2 18:43:29.042836 systemd[1]: flatcar-metadata-hostname.service was skipped because no trigger condition checks were met. Oct 2 18:43:29.042873 systemd[1]: ignition-remount-sysroot.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Oct 2 18:43:29.042908 systemd[1]: Reached target ignition-diskful.target. Oct 2 18:43:29.045665 systemd[1]: Mounted sysroot-usr.mount. Oct 2 18:43:29.047358 systemd[1]: Starting initrd-setup-root.service... Oct 2 18:43:29.054035 initrd-setup-root[791]: cut: /sysroot/etc/passwd: No such file or directory Oct 2 18:43:29.058500 initrd-setup-root[799]: cut: /sysroot/etc/group: No such file or directory Oct 2 18:43:29.063914 initrd-setup-root[807]: cut: /sysroot/etc/shadow: No such file or directory Oct 2 18:43:29.068840 initrd-setup-root[815]: cut: /sysroot/etc/gshadow: No such file or directory Oct 2 18:43:29.100206 systemd[1]: Finished initrd-setup-root.service. Oct 2 18:43:29.100000 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:43:29.101693 systemd[1]: Starting ignition-mount.service... Oct 2 18:43:29.102919 systemd[1]: Starting sysroot-boot.service... Oct 2 18:43:29.108608 bash[832]: umount: /sysroot/usr/share/oem: not mounted. Oct 2 18:43:29.119992 ignition[834]: INFO : Ignition 2.14.0 Oct 2 18:43:29.119992 ignition[834]: INFO : Stage: mount Oct 2 18:43:29.121202 ignition[834]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 2 18:43:29.121202 ignition[834]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 2 18:43:29.121202 ignition[834]: INFO : mount: op(1): [started] mounting "/dev/disk/by-label/OEM" at "/sysroot/usr/share/oem" with type "btrfs" and options "" Oct 2 18:43:29.121202 ignition[834]: DEBUG : mount: op(1): executing: "mount" "-o" "" "-t" "btrfs" "/dev/disk/by-label/OEM" "/sysroot/usr/share/oem" Oct 2 18:43:29.126000 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:43:29.126483 systemd[1]: Finished sysroot-boot.service. Oct 2 18:43:29.143935 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/vda6 scanned by mount (843) Oct 2 18:43:29.143985 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Oct 2 18:43:29.143995 kernel: BTRFS info (device vda6): using free space tree Oct 2 18:43:29.144371 kernel: BTRFS info (device vda6): has skinny extents Oct 2 18:43:29.148182 ignition[834]: INFO : mount: op(1): [finished] mounting "/dev/disk/by-label/OEM" at "/sysroot/usr/share/oem" with type "btrfs" and options "" Oct 2 18:43:29.148182 ignition[834]: INFO : mount: mount passed Oct 2 18:43:29.151000 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:43:29.151561 ignition[834]: INFO : Ignition finished successfully Oct 2 18:43:29.150775 systemd[1]: Finished ignition-mount.service. Oct 2 18:43:29.682704 systemd[1]: Condition check resulted in sysroot-usr-share-oem.mount being skipped. Oct 2 18:43:29.683822 systemd[1]: Starting ignition-files.service... Oct 2 18:43:29.701349 ignition[863]: INFO : Ignition 2.14.0 Oct 2 18:43:29.701349 ignition[863]: INFO : Stage: files Oct 2 18:43:29.702658 ignition[863]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 2 18:43:29.702658 ignition[863]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 2 18:43:29.702658 ignition[863]: DEBUG : files: compiled without relabeling support, skipping Oct 2 18:43:29.706497 ignition[863]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Oct 2 18:43:29.706497 ignition[863]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Oct 2 18:43:29.711810 ignition[863]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Oct 2 18:43:29.712781 ignition[863]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Oct 2 18:43:29.713752 unknown[863]: wrote ssh authorized keys file for user: core Oct 2 18:43:29.715082 ignition[863]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Oct 2 18:43:29.716047 ignition[863]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/enabled-sysext.conf" Oct 2 18:43:29.716047 ignition[863]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/enabled-sysext.conf" Oct 2 18:43:29.716047 ignition[863]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/usr/share/oem/oem-release" Oct 2 18:43:29.716047 ignition[863]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/usr/share/oem/oem-release" Oct 2 18:43:29.716047 ignition[863]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing file "/sysroot/etc/flatcar/sysext/flatcar-myext-3510.3.0.raw" Oct 2 18:43:29.716047 ignition[863]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing file "/sysroot/etc/flatcar/sysext/flatcar-myext-3510.3.0.raw" Oct 2 18:43:29.716047 ignition[863]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/usr/share/oem/sysext/active-oem-test" Oct 2 18:43:29.716047 ignition[863]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/usr/share/oem/sysext/active-oem-test" Oct 2 18:43:29.716047 ignition[863]: INFO : files: createFilesystemsFiles: createFiles: op(7): [started] writing file "/sysroot/usr/share/oem/sysext/oem-test-3510.3.0.raw" Oct 2 18:43:29.716047 ignition[863]: INFO : files: createFilesystemsFiles: createFiles: op(7): [finished] writing file "/sysroot/usr/share/oem/sysext/oem-test-3510.3.0.raw" Oct 2 18:43:29.716047 ignition[863]: INFO : files: op(8): [started] processing unit "systemd-sysext.service" Oct 2 18:43:29.716047 ignition[863]: INFO : files: op(8): [finished] processing unit "systemd-sysext.service" Oct 2 18:43:29.716047 ignition[863]: INFO : files: op(9): [started] masking unit "systemd-sysext.service" Oct 2 18:43:29.733033 ignition[863]: INFO : files: op(9): [finished] masking unit "systemd-sysext.service" Oct 2 18:43:29.733033 ignition[863]: INFO : files: op(a): [started] processing unit "ensure-sysext.service" Oct 2 18:43:29.733033 ignition[863]: INFO : files: op(a): [finished] processing unit "ensure-sysext.service" Oct 2 18:43:29.733033 ignition[863]: INFO : files: op(b): [started] masking unit "ensure-sysext.service" Oct 2 18:43:29.733033 ignition[863]: INFO : files: op(b): [finished] masking unit "ensure-sysext.service" Oct 2 18:43:29.733033 ignition[863]: INFO : files: op(c): [started] processing unit "coreos-metadata.service" Oct 2 18:43:29.733033 ignition[863]: INFO : files: op(c): op(d): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Oct 2 18:43:29.733033 ignition[863]: INFO : files: op(c): op(d): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Oct 2 18:43:29.733033 ignition[863]: INFO : files: op(c): [finished] processing unit "coreos-metadata.service" Oct 2 18:43:29.733033 ignition[863]: INFO : files: op(e): [started] setting preset to disabled for "coreos-metadata.service" Oct 2 18:43:29.733033 ignition[863]: INFO : files: op(e): op(f): [started] removing enablement symlink(s) for "coreos-metadata.service" Oct 2 18:43:29.767871 ignition[863]: INFO : files: op(e): op(f): [finished] removing enablement symlink(s) for "coreos-metadata.service" Oct 2 18:43:29.769490 ignition[863]: INFO : files: op(e): [finished] setting preset to disabled for "coreos-metadata.service" Oct 2 18:43:29.769490 ignition[863]: INFO : files: createResultFile: createFiles: op(10): [started] writing file "/sysroot/etc/.ignition-result.json" Oct 2 18:43:29.769490 ignition[863]: INFO : files: createResultFile: createFiles: op(10): [finished] writing file "/sysroot/etc/.ignition-result.json" Oct 2 18:43:29.769490 ignition[863]: INFO : files: files passed Oct 2 18:43:29.769490 ignition[863]: INFO : Ignition finished successfully Oct 2 18:43:29.769000 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:43:29.769653 systemd[1]: Finished ignition-files.service. Oct 2 18:43:29.781071 systemd[1]: Starting initrd-setup-root-after-ignition.service... Oct 2 18:43:29.781740 systemd[1]: torcx-profile-populate.service was skipped because of an unmet condition check (ConditionPathExists=/sysroot/etc/torcx/next-profile). Oct 2 18:43:29.782495 systemd[1]: Starting ignition-quench.service... Oct 2 18:43:29.786039 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 2 18:43:29.786122 systemd[1]: Finished ignition-quench.service. Oct 2 18:43:29.785000 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:43:29.785000 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:43:29.801720 systemd[1]: Finished initrd-setup-root-after-ignition.service. Oct 2 18:43:29.802000 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:43:29.802539 systemd[1]: Reached target ignition-complete.target. Oct 2 18:43:29.804234 systemd[1]: Starting initrd-parse-etc.service... Oct 2 18:43:29.819819 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 2 18:43:29.820000 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:43:29.820000 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:43:29.819906 systemd[1]: Finished initrd-parse-etc.service. Oct 2 18:43:29.820606 systemd[1]: Reached target initrd-fs.target. Oct 2 18:43:29.821537 systemd[1]: Reached target initrd.target. Oct 2 18:43:29.822479 systemd[1]: dracut-mount.service was skipped because no trigger condition checks were met. Oct 2 18:43:29.823152 systemd[1]: Starting dracut-pre-pivot.service... Oct 2 18:43:29.837753 systemd[1]: Finished dracut-pre-pivot.service. Oct 2 18:43:29.838000 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:43:29.839047 systemd[1]: Starting initrd-cleanup.service... Oct 2 18:43:29.848174 systemd[1]: Stopped target network.target. Oct 2 18:43:29.848823 systemd[1]: Stopped target nss-lookup.target. Oct 2 18:43:29.849735 systemd[1]: Stopped target remote-cryptsetup.target. Oct 2 18:43:29.850739 systemd[1]: Stopped target timers.target. Oct 2 18:43:29.851657 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 2 18:43:29.852000 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:43:29.851761 systemd[1]: Stopped dracut-pre-pivot.service. Oct 2 18:43:29.852654 systemd[1]: Stopped target initrd.target. Oct 2 18:43:29.853579 systemd[1]: Stopped target basic.target. Oct 2 18:43:29.854445 systemd[1]: Stopped target ignition-complete.target. Oct 2 18:43:29.855357 systemd[1]: Stopped target ignition-diskful.target. Oct 2 18:43:29.856280 systemd[1]: Stopped target initrd-root-device.target. Oct 2 18:43:29.857300 systemd[1]: Stopped target remote-fs.target. Oct 2 18:43:29.858245 systemd[1]: Stopped target remote-fs-pre.target. Oct 2 18:43:29.859250 systemd[1]: Stopped target sysinit.target. Oct 2 18:43:29.860155 systemd[1]: Stopped target local-fs.target. Oct 2 18:43:29.861091 systemd[1]: Stopped target local-fs-pre.target. Oct 2 18:43:29.861988 systemd[1]: Stopped target swap.target. Oct 2 18:43:29.863000 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:43:29.862825 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 2 18:43:29.862934 systemd[1]: Stopped dracut-pre-mount.service. Oct 2 18:43:29.865000 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:43:29.863887 systemd[1]: Stopped target cryptsetup.target. Oct 2 18:43:29.866000 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:43:29.864818 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 2 18:43:29.864919 systemd[1]: Stopped dracut-initqueue.service. Oct 2 18:43:29.866071 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Oct 2 18:43:29.866164 systemd[1]: Stopped ignition-fetch-offline.service. Oct 2 18:43:29.867036 systemd[1]: Stopped target paths.target. Oct 2 18:43:29.867844 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 2 18:43:29.870316 systemd[1]: Stopped systemd-ask-password-console.path. Oct 2 18:43:29.871319 systemd[1]: Stopped target slices.target. Oct 2 18:43:29.872562 systemd[1]: Stopped target sockets.target. Oct 2 18:43:29.873502 systemd[1]: iscsid.socket: Deactivated successfully. Oct 2 18:43:29.873571 systemd[1]: Closed iscsid.socket. Oct 2 18:43:29.875000 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:43:29.874361 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 2 18:43:29.876000 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:43:29.874432 systemd[1]: Closed iscsiuio.socket. Oct 2 18:43:29.875192 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Oct 2 18:43:29.875304 systemd[1]: Stopped initrd-setup-root-after-ignition.service. Oct 2 18:43:29.876284 systemd[1]: ignition-files.service: Deactivated successfully. Oct 2 18:43:29.876378 systemd[1]: Stopped ignition-files.service. Oct 2 18:43:29.877855 systemd[1]: Stopping ignition-mount.service... Oct 2 18:43:29.879178 systemd[1]: Stopping sysroot-boot.service... Oct 2 18:43:29.880394 systemd[1]: Stopping systemd-networkd.service... Oct 2 18:43:29.883000 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:43:29.882215 systemd[1]: Stopping systemd-resolved.service... Oct 2 18:43:29.884000 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:43:29.882786 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 2 18:43:29.882900 systemd[1]: Stopped systemd-udev-trigger.service. Oct 2 18:43:29.883950 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 2 18:43:29.889000 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:43:29.891940 ignition[906]: INFO : Ignition 2.14.0 Oct 2 18:43:29.891940 ignition[906]: INFO : Stage: umount Oct 2 18:43:29.891940 ignition[906]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 2 18:43:29.891940 ignition[906]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 2 18:43:29.891940 ignition[906]: INFO : umount: op(1): [started] umounting "/sysroot/usr/share/oem" Oct 2 18:43:29.893000 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:43:29.893000 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:43:29.893000 audit: BPF prog-id=6 op=UNLOAD Oct 2 18:43:29.884039 systemd[1]: Stopped dracut-pre-trigger.service. Oct 2 18:43:29.897000 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:43:29.888331 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 2 18:43:29.899422 ignition[906]: INFO : umount: op(1): [finished] umounting "/sysroot/usr/share/oem" Oct 2 18:43:29.899422 ignition[906]: INFO : umount: umount passed Oct 2 18:43:29.899422 ignition[906]: INFO : Ignition finished successfully Oct 2 18:43:29.899000 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:43:29.901000 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:43:29.888415 systemd[1]: Stopped systemd-resolved.service. Oct 2 18:43:29.902000 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:43:29.890660 systemd[1]: sysusr-usr-share-oem.mount: Deactivated successfully. Oct 2 18:43:29.903000 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:43:29.890728 systemd[1]: sysroot-usr-share-oem.mount: Deactivated successfully. Oct 2 18:43:29.892977 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 2 18:43:29.893057 systemd[1]: Finished initrd-cleanup.service. Oct 2 18:43:29.907000 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:43:29.893659 systemd-networkd[746]: eth0: DHCPv6 lease lost Oct 2 18:43:29.907000 audit: BPF prog-id=9 op=UNLOAD Oct 2 18:43:29.908000 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:43:29.908000 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:43:29.895805 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 2 18:43:29.895893 systemd[1]: Stopped systemd-networkd.service. Oct 2 18:43:29.898890 systemd[1]: sysroot-boot.mount: Deactivated successfully. Oct 2 18:43:29.899233 systemd[1]: ignition-mount.service: Deactivated successfully. Oct 2 18:43:29.913000 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:43:29.899308 systemd[1]: Stopped ignition-mount.service. Oct 2 18:43:29.900014 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 2 18:43:29.915000 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:43:29.900039 systemd[1]: Closed systemd-networkd.socket. Oct 2 18:43:29.901112 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 2 18:43:29.918000 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:43:29.901148 systemd[1]: Stopped ignition-disks.service. Oct 2 18:43:29.902078 systemd[1]: ignition-kargs.service: Deactivated successfully. Oct 2 18:43:29.902109 systemd[1]: Stopped ignition-kargs.service. Oct 2 18:43:29.903045 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 2 18:43:29.919000 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:43:29.903075 systemd[1]: Stopped ignition-setup.service. Oct 2 18:43:29.923000 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:43:29.904674 systemd[1]: Stopping network-cleanup.service... Oct 2 18:43:29.923000 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:43:29.905683 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Oct 2 18:43:29.925000 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:43:29.905732 systemd[1]: Stopped parse-ip-for-networkd.service. Oct 2 18:43:29.907701 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 2 18:43:29.907748 systemd[1]: Stopped systemd-sysctl.service. Oct 2 18:43:29.908633 systemd[1]: systemd-modules-load.service: Deactivated successfully. Oct 2 18:43:29.908669 systemd[1]: Stopped systemd-modules-load.service. Oct 2 18:43:29.911398 systemd[1]: Stopping systemd-udevd.service... Oct 2 18:43:29.912846 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Oct 2 18:43:29.928000 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:43:29.929000 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:43:29.930000 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:43:29.913397 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 2 18:43:29.933000 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:43:29.933000 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:43:29.913470 systemd[1]: Stopped sysroot-boot.service. Oct 2 18:43:29.914868 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 2 18:43:29.914930 systemd[1]: Stopped initrd-setup-root.service. Oct 2 18:43:29.917288 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 2 18:43:29.917373 systemd[1]: Stopped network-cleanup.service. Oct 2 18:43:29.919092 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 2 18:43:29.919204 systemd[1]: Stopped systemd-udevd.service. Oct 2 18:43:29.920253 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 2 18:43:29.920299 systemd[1]: Closed systemd-udevd-control.socket. Oct 2 18:43:29.921482 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 2 18:43:29.921515 systemd[1]: Closed systemd-udevd-kernel.socket. Oct 2 18:43:29.922575 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 2 18:43:29.922619 systemd[1]: Stopped dracut-pre-udev.service. Oct 2 18:43:29.923819 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 2 18:43:29.923860 systemd[1]: Stopped dracut-cmdline.service. Oct 2 18:43:29.924896 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 2 18:43:29.924936 systemd[1]: Stopped dracut-cmdline-ask.service. Oct 2 18:43:29.926485 systemd[1]: Starting initrd-udevadm-cleanup-db.service... Oct 2 18:43:29.927577 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 2 18:43:29.927637 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service. Oct 2 18:43:29.929188 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 2 18:43:29.929229 systemd[1]: Stopped kmod-static-nodes.service. Oct 2 18:43:29.929953 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 2 18:43:29.929988 systemd[1]: Stopped systemd-vconsole-setup.service. Oct 2 18:43:29.931647 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Oct 2 18:43:29.932525 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 2 18:43:29.932603 systemd[1]: Finished initrd-udevadm-cleanup-db.service. Oct 2 18:43:29.933536 systemd[1]: Reached target initrd-switch-root.target. Oct 2 18:43:29.935141 systemd[1]: Starting initrd-switch-root.service... Oct 2 18:43:29.942623 systemd[1]: Switching root. Oct 2 18:43:29.961599 iscsid[752]: iscsid shutting down. Oct 2 18:43:29.962085 systemd-journald[290]: Journal stopped Oct 2 18:43:31.987194 systemd-journald[290]: Received SIGTERM from PID 1 (n/a). Oct 2 18:43:31.987391 kernel: SELinux: Class mctp_socket not defined in policy. Oct 2 18:43:31.987408 kernel: SELinux: Class anon_inode not defined in policy. Oct 2 18:43:31.987418 kernel: SELinux: the above unknown classes and permissions will be allowed Oct 2 18:43:31.987428 kernel: SELinux: policy capability network_peer_controls=1 Oct 2 18:43:31.987440 kernel: SELinux: policy capability open_perms=1 Oct 2 18:43:31.987450 kernel: SELinux: policy capability extended_socket_class=1 Oct 2 18:43:31.987460 kernel: SELinux: policy capability always_check_network=0 Oct 2 18:43:31.987469 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 2 18:43:31.987479 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 2 18:43:31.987488 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 2 18:43:31.987507 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Oct 2 18:43:31.987518 systemd[1]: Successfully loaded SELinux policy in 38.535ms. Oct 2 18:43:31.987548 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 7.007ms. Oct 2 18:43:31.987562 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:43:31.987573 systemd[1]: Detected virtualization kvm. Oct 2 18:43:31.987583 systemd[1]: Detected architecture arm64. Oct 2 18:43:31.987594 systemd[1]: Detected first boot. Oct 2 18:43:31.987604 systemd[1]: Initializing machine ID from VM UUID. Oct 2 18:43:31.987615 systemd[1]: Populated /etc with preset unit settings. Oct 2 18:43:31.987625 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:43:31.987637 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:43:31.987649 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:43:31.987660 kernel: kauditd_printk_skb: 70 callbacks suppressed Oct 2 18:43:31.987669 kernel: audit: type=1334 audit(1696272211.858:81): prog-id=12 op=LOAD Oct 2 18:43:31.987679 kernel: audit: type=1334 audit(1696272211.860:82): prog-id=3 op=UNLOAD Oct 2 18:43:31.987688 kernel: audit: type=1334 audit(1696272211.860:83): prog-id=13 op=LOAD Oct 2 18:43:31.987698 kernel: audit: type=1334 audit(1696272211.861:84): prog-id=14 op=LOAD Oct 2 18:43:31.987707 systemd[1]: iscsiuio.service: Deactivated successfully. Oct 2 18:43:31.987718 kernel: audit: type=1334 audit(1696272211.861:85): prog-id=4 op=UNLOAD Oct 2 18:43:31.987728 systemd[1]: Stopped iscsiuio.service. Oct 2 18:43:31.987738 kernel: audit: type=1334 audit(1696272211.861:86): prog-id=5 op=UNLOAD Oct 2 18:43:31.987747 kernel: audit: type=1131 audit(1696272211.862:87): 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:43:31.987758 kernel: audit: type=1131 audit(1696272211.867:88): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=iscsiuio comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 18:43:31.987768 systemd[1]: iscsid.service: Deactivated successfully. Oct 2 18:43:31.987778 kernel: audit: type=1334 audit(1696272211.869:89): prog-id=12 op=UNLOAD Oct 2 18:43:31.987788 systemd[1]: Stopped iscsid.service. Oct 2 18:43:31.987799 kernel: audit: type=1131 audit(1696272211.870:90): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 18:43:31.987809 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 2 18:43:31.987828 systemd[1]: Stopped initrd-switch-root.service. Oct 2 18:43:31.987838 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 2 18:43:31.987848 systemd[1]: Created slice system-addon\x2dconfig.slice. Oct 2 18:43:31.987859 systemd[1]: Created slice system-addon\x2drun.slice. Oct 2 18:43:31.987877 systemd[1]: Created slice system-getty.slice. Oct 2 18:43:31.987888 systemd[1]: Created slice system-modprobe.slice. Oct 2 18:43:31.987899 systemd[1]: Created slice system-serial\x2dgetty.slice. Oct 2 18:43:31.987911 systemd[1]: Created slice system-system\x2dcloudinit.slice. Oct 2 18:43:31.987923 systemd[1]: Created slice system-systemd\x2dfsck.slice. Oct 2 18:43:31.987934 systemd[1]: Created slice user.slice. Oct 2 18:43:31.987944 systemd[1]: Started systemd-ask-password-console.path. Oct 2 18:43:31.987955 systemd[1]: Started systemd-ask-password-wall.path. Oct 2 18:43:31.987965 systemd[1]: Set up automount boot.automount. Oct 2 18:43:31.987975 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount. Oct 2 18:43:31.987985 systemd[1]: Stopped target initrd-switch-root.target. Oct 2 18:43:31.987996 systemd[1]: Stopped target initrd-fs.target. Oct 2 18:43:31.988016 systemd[1]: Stopped target initrd-root-fs.target. Oct 2 18:43:31.988026 systemd[1]: Reached target integritysetup.target. Oct 2 18:43:31.988038 systemd[1]: Reached target remote-cryptsetup.target. Oct 2 18:43:31.988048 systemd[1]: Reached target remote-fs.target. Oct 2 18:43:31.988058 systemd[1]: Reached target slices.target. Oct 2 18:43:31.988069 systemd[1]: Reached target swap.target. Oct 2 18:43:31.988079 systemd[1]: Reached target torcx.target. Oct 2 18:43:31.988089 systemd[1]: Reached target veritysetup.target. Oct 2 18:43:31.988099 systemd[1]: Listening on systemd-coredump.socket. Oct 2 18:43:31.988110 systemd[1]: Listening on systemd-initctl.socket. Oct 2 18:43:31.988120 systemd[1]: Listening on systemd-networkd.socket. Oct 2 18:43:31.988130 systemd[1]: Listening on systemd-udevd-control.socket. Oct 2 18:43:31.988142 systemd[1]: Listening on systemd-udevd-kernel.socket. Oct 2 18:43:31.988152 systemd[1]: Listening on systemd-userdbd.socket. Oct 2 18:43:31.988163 systemd[1]: Mounting dev-hugepages.mount... Oct 2 18:43:31.988173 systemd[1]: Mounting dev-mqueue.mount... Oct 2 18:43:31.988184 systemd[1]: Mounting media.mount... Oct 2 18:43:31.988194 systemd[1]: Mounting sys-kernel-debug.mount... Oct 2 18:43:31.988204 systemd[1]: Mounting sys-kernel-tracing.mount... Oct 2 18:43:31.988215 systemd[1]: Mounting tmp.mount... Oct 2 18:43:31.988226 systemd[1]: Starting flatcar-tmpfiles.service... Oct 2 18:43:31.988238 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Oct 2 18:43:31.988249 systemd[1]: Starting kmod-static-nodes.service... Oct 2 18:43:31.988259 systemd[1]: Starting modprobe@configfs.service... Oct 2 18:43:31.988278 systemd[1]: Starting modprobe@dm_mod.service... Oct 2 18:43:31.988290 systemd[1]: Starting modprobe@drm.service... Oct 2 18:43:31.988301 systemd[1]: Starting modprobe@efi_pstore.service... Oct 2 18:43:31.988311 systemd[1]: Starting modprobe@fuse.service... Oct 2 18:43:31.988321 systemd[1]: Starting modprobe@loop.service... Oct 2 18:43:31.988331 systemd[1]: setup-nsswitch.service was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Oct 2 18:43:31.988344 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 2 18:43:31.988354 systemd[1]: Stopped systemd-fsck-root.service. Oct 2 18:43:31.988364 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 2 18:43:31.988375 systemd[1]: Stopped systemd-fsck-usr.service. Oct 2 18:43:31.988385 systemd[1]: Stopped systemd-journald.service. Oct 2 18:43:31.988397 systemd[1]: Starting systemd-journald.service... Oct 2 18:43:31.988407 kernel: loop: module loaded Oct 2 18:43:31.988416 systemd[1]: Starting systemd-modules-load.service... Oct 2 18:43:31.988427 systemd[1]: Starting systemd-network-generator.service... Oct 2 18:43:31.988438 kernel: fuse: init (API version 7.34) Oct 2 18:43:31.988448 systemd[1]: Starting systemd-remount-fs.service... Oct 2 18:43:31.988459 systemd[1]: Starting systemd-udev-trigger.service... Oct 2 18:43:31.988469 systemd[1]: verity-setup.service: Deactivated successfully. Oct 2 18:43:31.988480 systemd[1]: Stopped verity-setup.service. Oct 2 18:43:31.988490 systemd[1]: Mounted dev-hugepages.mount. Oct 2 18:43:31.988500 systemd[1]: Mounted dev-mqueue.mount. Oct 2 18:43:31.988510 systemd[1]: Mounted media.mount. Oct 2 18:43:31.988520 systemd[1]: Mounted sys-kernel-debug.mount. Oct 2 18:43:31.988530 systemd[1]: Mounted sys-kernel-tracing.mount. Oct 2 18:43:31.988546 systemd-journald[1006]: Journal started Oct 2 18:43:31.988591 systemd-journald[1006]: Runtime Journal (/run/log/journal/11754ca7bb294dd7a8fb562d7bd617fc) is 6.0M, max 48.7M, 42.6M free. Oct 2 18:43:30.048000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 2 18:43:30.083000 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:43:30.083000 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:43:30.083000 audit: BPF prog-id=10 op=LOAD Oct 2 18:43:30.083000 audit: BPF prog-id=10 op=UNLOAD Oct 2 18:43:30.083000 audit: BPF prog-id=11 op=LOAD Oct 2 18:43:30.083000 audit: BPF prog-id=11 op=UNLOAD Oct 2 18:43:31.858000 audit: BPF prog-id=12 op=LOAD Oct 2 18:43:31.860000 audit: BPF prog-id=3 op=UNLOAD Oct 2 18:43:31.860000 audit: BPF prog-id=13 op=LOAD Oct 2 18:43:31.861000 audit: BPF prog-id=14 op=LOAD Oct 2 18:43:31.861000 audit: BPF prog-id=4 op=UNLOAD Oct 2 18:43:31.861000 audit: BPF prog-id=5 op=UNLOAD Oct 2 18:43:31.862000 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:43:31.867000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=iscsiuio comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 18:43:31.869000 audit: BPF prog-id=12 op=UNLOAD Oct 2 18:43:31.870000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 18:43:31.874000 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:43:31.874000 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:43:31.955000 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:43:31.957000 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:43:31.958000 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:43:31.958000 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:43:31.959000 audit: BPF prog-id=15 op=LOAD Oct 2 18:43:31.959000 audit: BPF prog-id=16 op=LOAD Oct 2 18:43:31.959000 audit: BPF prog-id=17 op=LOAD Oct 2 18:43:31.959000 audit: BPF prog-id=13 op=UNLOAD Oct 2 18:43:31.959000 audit: BPF prog-id=14 op=UNLOAD Oct 2 18:43:31.979000 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:43:31.985000 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:43:31.985000 audit[1006]: SYSCALL arch=c00000b7 syscall=211 success=yes exit=60 a0=6 a1=ffffef126a20 a2=4000 a3=1 items=0 ppid=1 pid=1006 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:43:31.985000 audit: PROCTITLE proctitle="/usr/lib/systemd/systemd-journald" Oct 2 18:43:30.135777 /usr/lib/systemd/system-generators/torcx-generator[940]: time="2023-10-02T18:43:30Z" 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:43:31.855418 systemd[1]: Queued start job for default target multi-user.target. Oct 2 18:43:31.989494 systemd[1]: Mounted tmp.mount. Oct 2 18:43:30.136426 /usr/lib/systemd/system-generators/torcx-generator[940]: time="2023-10-02T18:43:30Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 2 18:43:31.862079 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 2 18:43:30.136445 /usr/lib/systemd/system-generators/torcx-generator[940]: time="2023-10-02T18:43:30Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 2 18:43:30.136551 /usr/lib/systemd/system-generators/torcx-generator[940]: time="2023-10-02T18:43:30Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Oct 2 18:43:30.136561 /usr/lib/systemd/system-generators/torcx-generator[940]: time="2023-10-02T18:43:30Z" level=debug msg="skipped missing lower profile" missing profile=oem Oct 2 18:43:30.136588 /usr/lib/systemd/system-generators/torcx-generator[940]: time="2023-10-02T18:43:30Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Oct 2 18:43:30.136600 /usr/lib/systemd/system-generators/torcx-generator[940]: time="2023-10-02T18:43:30Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Oct 2 18:43:30.136797 /usr/lib/systemd/system-generators/torcx-generator[940]: time="2023-10-02T18:43:30Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Oct 2 18:43:30.136831 /usr/lib/systemd/system-generators/torcx-generator[940]: time="2023-10-02T18:43:30Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 2 18:43:30.136842 /usr/lib/systemd/system-generators/torcx-generator[940]: time="2023-10-02T18:43:30Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 2 18:43:30.137244 /usr/lib/systemd/system-generators/torcx-generator[940]: time="2023-10-02T18:43:30Z" 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:43:30.137295 /usr/lib/systemd/system-generators/torcx-generator[940]: time="2023-10-02T18:43:30Z" 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:43:30.137312 /usr/lib/systemd/system-generators/torcx-generator[940]: time="2023-10-02T18:43:30Z" 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:43:31.990460 systemd[1]: Started systemd-journald.service. Oct 2 18:43:30.137326 /usr/lib/systemd/system-generators/torcx-generator[940]: time="2023-10-02T18:43:30Z" 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:43:31.990000 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:43:30.137342 /usr/lib/systemd/system-generators/torcx-generator[940]: time="2023-10-02T18:43:30Z" 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:43:30.137355 /usr/lib/systemd/system-generators/torcx-generator[940]: time="2023-10-02T18:43:30Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Oct 2 18:43:31.598314 /usr/lib/systemd/system-generators/torcx-generator[940]: time="2023-10-02T18:43:31Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 2 18:43:31.598567 /usr/lib/systemd/system-generators/torcx-generator[940]: time="2023-10-02T18:43:31Z" 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:43:31.598673 /usr/lib/systemd/system-generators/torcx-generator[940]: time="2023-10-02T18:43:31Z" 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:43:31.598822 /usr/lib/systemd/system-generators/torcx-generator[940]: time="2023-10-02T18:43:31Z" 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:43:31.598893 /usr/lib/systemd/system-generators/torcx-generator[940]: time="2023-10-02T18:43:31Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Oct 2 18:43:31.598958 /usr/lib/systemd/system-generators/torcx-generator[940]: time="2023-10-02T18:43:31Z" 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:43:31.991496 systemd[1]: Finished kmod-static-nodes.service. Oct 2 18:43:31.991000 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:43:31.992380 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 2 18:43:31.992535 systemd[1]: Finished modprobe@configfs.service. Oct 2 18:43:31.992000 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:43:31.992000 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:43:31.993391 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 2 18:43:31.993540 systemd[1]: Finished modprobe@dm_mod.service. Oct 2 18:43:31.993000 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:43:31.993000 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:43:31.994365 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 2 18:43:31.994521 systemd[1]: Finished modprobe@drm.service. Oct 2 18:43:31.994000 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:43:31.994000 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:43:31.995342 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 2 18:43:31.995496 systemd[1]: Finished modprobe@efi_pstore.service. Oct 2 18:43:31.995000 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:43:31.995000 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:43:31.996400 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 2 18:43:31.996549 systemd[1]: Finished modprobe@fuse.service. Oct 2 18:43:31.996000 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:43:31.996000 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:43:31.997367 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 2 18:43:31.997531 systemd[1]: Finished modprobe@loop.service. Oct 2 18:43:31.997000 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:43:31.997000 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:43:31.998580 systemd[1]: Finished systemd-modules-load.service. Oct 2 18:43:31.998000 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:43:31.999490 systemd[1]: Finished systemd-network-generator.service. Oct 2 18:43:31.999000 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:43:32.000548 systemd[1]: Finished systemd-remount-fs.service. Oct 2 18:43:32.000000 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:43:32.001463 systemd[1]: Finished flatcar-tmpfiles.service. Oct 2 18:43:32.001000 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:43:32.002646 systemd[1]: Reached target network-pre.target. Oct 2 18:43:32.004556 systemd[1]: Mounting sys-fs-fuse-connections.mount... Oct 2 18:43:32.006191 systemd[1]: Mounting sys-kernel-config.mount... Oct 2 18:43:32.006942 systemd[1]: remount-root.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Oct 2 18:43:32.009586 systemd[1]: Starting systemd-hwdb-update.service... Oct 2 18:43:32.011601 systemd[1]: Starting systemd-journal-flush.service... Oct 2 18:43:32.012563 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 2 18:43:32.013786 systemd[1]: Starting systemd-random-seed.service... Oct 2 18:43:32.014861 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Oct 2 18:43:32.016260 systemd[1]: Starting systemd-sysctl.service... Oct 2 18:43:32.018244 systemd[1]: Starting systemd-sysusers.service... Oct 2 18:43:32.022920 systemd[1]: Mounted sys-fs-fuse-connections.mount. Oct 2 18:43:32.023904 systemd[1]: Mounted sys-kernel-config.mount. Oct 2 18:43:32.028619 systemd[1]: Finished systemd-udev-trigger.service. Oct 2 18:43:32.030457 systemd[1]: Starting systemd-udev-settle.service... Oct 2 18:43:32.028000 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:43:32.033421 systemd-journald[1006]: Time spent on flushing to /var/log/journal/11754ca7bb294dd7a8fb562d7bd617fc is 13.397ms for 975 entries. Oct 2 18:43:32.033421 systemd-journald[1006]: System Journal (/var/log/journal/11754ca7bb294dd7a8fb562d7bd617fc) is 8.0M, max 195.6M, 187.6M free. Oct 2 18:43:32.079368 systemd-journald[1006]: Received client request to flush runtime journal. Oct 2 18:43:32.049000 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:43:32.051000 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:43:32.048731 systemd[1]: Finished systemd-sysctl.service. Oct 2 18:43:32.079721 udevadm[1040]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Oct 2 18:43:32.051353 systemd[1]: Finished systemd-random-seed.service. Oct 2 18:43:32.052042 systemd[1]: Reached target first-boot-complete.target. Oct 2 18:43:32.080487 systemd[1]: Finished systemd-journal-flush.service. Oct 2 18:43:32.080000 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:43:32.089887 systemd[1]: Finished systemd-sysusers.service. Oct 2 18:43:32.090000 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:43:32.091732 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... Oct 2 18:43:32.114185 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. Oct 2 18:43:32.114000 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:43:32.452583 systemd[1]: Finished systemd-hwdb-update.service. Oct 2 18:43:32.452000 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:43:32.453000 audit: BPF prog-id=18 op=LOAD Oct 2 18:43:32.453000 audit: BPF prog-id=19 op=LOAD Oct 2 18:43:32.453000 audit: BPF prog-id=7 op=UNLOAD Oct 2 18:43:32.453000 audit: BPF prog-id=8 op=UNLOAD Oct 2 18:43:32.454571 systemd[1]: Starting systemd-udevd.service... Oct 2 18:43:32.473059 systemd-udevd[1045]: Using default interface naming scheme 'v252'. Oct 2 18:43:32.497025 systemd[1]: Started systemd-udevd.service. Oct 2 18:43:32.497000 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:43:32.498000 audit: BPF prog-id=20 op=LOAD Oct 2 18:43:32.499349 systemd[1]: Starting systemd-networkd.service... Oct 2 18:43:32.516000 audit: BPF prog-id=21 op=LOAD Oct 2 18:43:32.516000 audit: BPF prog-id=22 op=LOAD Oct 2 18:43:32.516000 audit: BPF prog-id=23 op=LOAD Oct 2 18:43:32.517884 systemd[1]: Starting systemd-userdbd.service... Oct 2 18:43:32.524718 systemd[1]: Condition check resulted in dev-ttyAMA0.device being skipped. Oct 2 18:43:32.540301 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 scanned by (udev-worker) (1048) Oct 2 18:43:32.558052 systemd[1]: Started systemd-userdbd.service. Oct 2 18:43:32.558000 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:43:32.576902 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Oct 2 18:43:32.615111 systemd[1]: Finished systemd-udev-settle.service. Oct 2 18:43:32.615000 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:43:32.617112 systemd[1]: Starting lvm2-activation-early.service... Oct 2 18:43:32.634563 systemd-networkd[1052]: lo: Link UP Oct 2 18:43:32.634571 systemd-networkd[1052]: lo: Gained carrier Oct 2 18:43:32.634908 systemd-networkd[1052]: Enumeration completed Oct 2 18:43:32.635025 systemd[1]: Started systemd-networkd.service. Oct 2 18:43:32.635025 systemd-networkd[1052]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 2 18:43:32.635000 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:43:32.637202 lvm[1079]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 2 18:43:32.637200 systemd-networkd[1052]: eth0: Link UP Oct 2 18:43:32.637204 systemd-networkd[1052]: eth0: Gained carrier Oct 2 18:43:32.657409 systemd-networkd[1052]: eth0: DHCPv4 address 10.0.0.10/16, gateway 10.0.0.1 acquired from 10.0.0.1 Oct 2 18:43:32.670208 systemd[1]: Finished lvm2-activation-early.service. Oct 2 18:43:32.670000 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:43:32.670972 systemd[1]: Reached target cryptsetup.target. Oct 2 18:43:32.672642 systemd[1]: Starting lvm2-activation.service... Oct 2 18:43:32.676917 lvm[1080]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 2 18:43:32.707583 systemd[1]: Finished lvm2-activation.service. Oct 2 18:43:32.707000 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:43:32.708331 systemd[1]: Reached target local-fs-pre.target. Oct 2 18:43:32.708927 systemd[1]: var-lib-machines.mount was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Oct 2 18:43:32.708945 systemd[1]: Reached target machines.target. Oct 2 18:43:32.710571 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service... Oct 2 18:43:32.732604 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service. Oct 2 18:43:32.733000 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:43:32.734609 systemd[1]: Mounting usr-share-oem.mount... Oct 2 18:43:32.751706 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Oct 2 18:43:32.751816 kernel: BTRFS info (device vda6): using free space tree Oct 2 18:43:32.751837 kernel: BTRFS info (device vda6): has skinny extents Oct 2 18:43:32.761084 systemd[1]: Mounted usr-share-oem.mount. Oct 2 18:43:32.761832 systemd[1]: Reached target local-fs.target. Oct 2 18:43:32.765664 systemd[1]: Starting ldconfig.service... Oct 2 18:43:32.766615 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Oct 2 18:43:32.766683 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:43:32.768018 systemd[1]: Starting systemd-boot-update.service... Oct 2 18:43:32.771502 systemd[1]: Starting systemd-machine-id-commit.service... Oct 2 18:43:32.773479 systemd[1]: Starting systemd-tmpfiles-setup.service... Oct 2 18:43:32.779041 systemd[1]: boot.automount: Got automount request for /boot, triggered by 1104 (bootctl) Oct 2 18:43:32.780090 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service... Oct 2 18:43:32.786502 systemd-tmpfiles[1106]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Oct 2 18:43:32.795979 systemd-tmpfiles[1106]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Oct 2 18:43:32.797144 systemd-tmpfiles[1106]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Oct 2 18:43:32.804375 systemd[1]: Finished systemd-machine-id-commit.service. Oct 2 18:43:32.804000 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:43:32.833317 systemd-fsck[1110]: fsck.fat 4.2 (2021-01-31) Oct 2 18:43:32.833317 systemd-fsck[1110]: /dev/vda1: 236 files, 113463/258078 clusters Oct 2 18:43:32.835841 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service. Oct 2 18:43:32.836000 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:43:32.981490 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 2 18:43:32.983133 systemd[1]: Mounting boot.mount... Oct 2 18:43:32.993819 systemd[1]: Mounted boot.mount. Oct 2 18:43:33.004128 systemd[1]: Finished systemd-boot-update.service. Oct 2 18:43:33.004000 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:43:33.031480 ldconfig[1103]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 2 18:43:33.037973 systemd[1]: Finished ldconfig.service. Oct 2 18:43:33.038000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=ldconfig comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 18:43:33.071180 systemd[1]: Finished systemd-tmpfiles-setup.service. Oct 2 18:43:33.071000 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:43:33.073148 systemd[1]: Starting audit-rules.service... Oct 2 18:43:33.074695 systemd[1]: Starting clean-ca-certificates.service... Oct 2 18:43:33.076491 systemd[1]: Starting systemd-journal-catalog-update.service... Oct 2 18:43:33.077000 audit: BPF prog-id=24 op=LOAD Oct 2 18:43:33.078775 systemd[1]: Starting systemd-resolved.service... Oct 2 18:43:33.083000 audit: BPF prog-id=25 op=LOAD Oct 2 18:43:33.084242 systemd[1]: Starting systemd-timesyncd.service... Oct 2 18:43:33.085848 systemd[1]: Starting systemd-update-utmp.service... Oct 2 18:43:33.087678 systemd[1]: Finished clean-ca-certificates.service. Oct 2 18:43:33.087000 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:43:33.088620 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Oct 2 18:43:33.092000 audit[1124]: SYSTEM_BOOT pid=1124 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:43:33.095654 systemd[1]: Finished systemd-update-utmp.service. Oct 2 18:43:33.095000 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:43:33.099456 systemd[1]: Finished systemd-journal-catalog-update.service. Oct 2 18:43:33.099000 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:43:33.101335 systemd[1]: Starting systemd-update-done.service... Oct 2 18:43:33.109454 systemd[1]: Finished systemd-update-done.service. Oct 2 18:43:33.109000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-update-done comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 18:43:33.130485 augenrules[1134]: No rules Oct 2 18:43:33.128000 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:43:33.128000 audit[1134]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffef320f10 a2=420 a3=0 items=0 ppid=1113 pid=1134 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:43:33.128000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573 Oct 2 18:43:33.129658 systemd[1]: Finished audit-rules.service. Oct 2 18:43:33.139000 systemd[1]: Started systemd-timesyncd.service. Oct 2 18:43:32.680108 systemd[1]: Reached target time-set.target. Oct 2 18:43:32.696611 systemd-journald[1006]: Time jumped backwards, rotating. Oct 2 18:43:32.680215 systemd-timesyncd[1123]: Contacted time server 10.0.0.1:123 (10.0.0.1). Oct 2 18:43:32.680286 systemd-timesyncd[1123]: Initial clock synchronization to Mon 2023-10-02 18:43:32.680084 UTC. Oct 2 18:43:32.689182 systemd-resolved[1117]: Positive Trust Anchors: Oct 2 18:43:32.689189 systemd-resolved[1117]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 2 18:43:32.689215 systemd-resolved[1117]: 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:43:32.701394 systemd-resolved[1117]: Defaulting to hostname 'linux'. Oct 2 18:43:32.702823 systemd[1]: Started systemd-resolved.service. Oct 2 18:43:32.703461 systemd[1]: Reached target network.target. Oct 2 18:43:32.703972 systemd[1]: Reached target nss-lookup.target. Oct 2 18:43:32.704539 systemd[1]: Reached target sysinit.target. Oct 2 18:43:32.705108 systemd[1]: Started motdgen.path. Oct 2 18:43:32.705626 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path. Oct 2 18:43:32.706496 systemd[1]: Started logrotate.timer. Oct 2 18:43:32.707184 systemd[1]: Started mdadm.timer. Oct 2 18:43:32.707689 systemd[1]: Started systemd-tmpfiles-clean.timer. Oct 2 18:43:32.708238 systemd[1]: update-engine-stub.timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Oct 2 18:43:32.708285 systemd[1]: Reached target paths.target. Oct 2 18:43:32.708770 systemd[1]: Reached target timers.target. Oct 2 18:43:32.709615 systemd[1]: Listening on dbus.socket. Oct 2 18:43:32.711104 systemd[1]: Starting docker.socket... Oct 2 18:43:32.714786 systemd[1]: Listening on sshd.socket. Oct 2 18:43:32.715430 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:43:32.715873 systemd[1]: Listening on docker.socket. Oct 2 18:43:32.716509 systemd[1]: Reached target sockets.target. Oct 2 18:43:32.717278 systemd[1]: Reached target basic.target. Oct 2 18:43:32.718037 systemd[1]: addon-config@usr-share-oem.service was skipped because no trigger condition checks were met. Oct 2 18:43:32.718067 systemd[1]: addon-run@usr-share-oem.service was skipped because no trigger condition checks were met. Oct 2 18:43:32.719144 systemd[1]: Starting containerd.service... Oct 2 18:43:32.721032 systemd[1]: Starting dbus.service... Oct 2 18:43:32.722940 systemd[1]: Starting enable-oem-cloudinit.service... Oct 2 18:43:32.725133 systemd[1]: Starting extend-filesystems.service... Oct 2 18:43:32.725760 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:43:32.726942 systemd[1]: Starting motdgen.service... Oct 2 18:43:32.729441 systemd[1]: Starting ssh-key-proc-cmdline.service... Oct 2 18:43:32.732571 systemd[1]: Starting sshd-keygen.service... Oct 2 18:43:32.735238 systemd[1]: Starting systemd-logind.service... Oct 2 18:43:32.735767 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:43:32.735822 systemd[1]: tcsd.service was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Oct 2 18:43:32.736209 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 2 18:43:32.736841 systemd[1]: Starting update-engine.service... Oct 2 18:43:32.738390 systemd[1]: Starting update-ssh-keys-after-ignition.service... Oct 2 18:43:32.742020 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 2 18:43:32.742194 systemd[1]: Finished ssh-key-proc-cmdline.service. Oct 2 18:43:32.747550 jq[1145]: false Oct 2 18:43:32.748287 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Oct 2 18:43:32.752035 jq[1156]: true Oct 2 18:43:32.748447 systemd[1]: Condition check resulted in enable-oem-cloudinit.service being skipped. Oct 2 18:43:32.763663 jq[1167]: true Oct 2 18:43:32.764933 systemd[1]: motdgen.service: Deactivated successfully. Oct 2 18:43:32.765111 systemd[1]: Finished motdgen.service. Oct 2 18:43:32.766618 extend-filesystems[1146]: Found vda Oct 2 18:43:32.767640 extend-filesystems[1146]: Found vda1 Oct 2 18:43:32.768266 extend-filesystems[1146]: Found vda2 Oct 2 18:43:32.768914 extend-filesystems[1146]: Found vda3 Oct 2 18:43:32.769628 extend-filesystems[1146]: Found usr Oct 2 18:43:32.770216 extend-filesystems[1146]: Found vda4 Oct 2 18:43:32.770807 extend-filesystems[1146]: Found vda6 Oct 2 18:43:32.771368 extend-filesystems[1146]: Found vda7 Oct 2 18:43:32.771938 extend-filesystems[1146]: Found vda9 Oct 2 18:43:32.772683 extend-filesystems[1146]: Checking size of /dev/vda9 Oct 2 18:43:32.784414 dbus-daemon[1144]: [system] SELinux support is enabled Oct 2 18:43:32.784910 systemd[1]: Started dbus.service. Oct 2 18:43:32.787144 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:43:32.787176 systemd[1]: Reached target system-config.target. Oct 2 18:43:32.787913 systemd[1]: user-cloudinit-proc-cmdline.service was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Oct 2 18:43:32.787933 systemd[1]: Reached target user-config.target. Oct 2 18:43:32.801522 extend-filesystems[1146]: Old size kept for /dev/vda9 Oct 2 18:43:32.805071 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 2 18:43:32.812001 systemd[1]: Finished extend-filesystems.service. Oct 2 18:43:32.815420 systemd-logind[1152]: Watching system buttons on /dev/input/event0 (Power Button) Oct 2 18:43:32.816219 systemd-logind[1152]: New seat seat0. Oct 2 18:43:32.817944 systemd[1]: Started systemd-logind.service. Oct 2 18:43:32.834852 bash[1191]: Updated "/home/core/.ssh/authorized_keys" Oct 2 18:43:32.836216 update_engine[1153]: I1002 18:43:32.835999 1153 main.cc:92] Flatcar Update Engine starting Oct 2 18:43:32.837274 systemd[1]: Finished update-ssh-keys-after-ignition.service. Oct 2 18:43:32.839968 systemd[1]: Started update-engine.service. Oct 2 18:43:32.840038 update_engine[1153]: I1002 18:43:32.840013 1153 update_check_scheduler.cc:74] Next update check in 5m27s Oct 2 18:43:32.842276 systemd[1]: Started locksmithd.service. Oct 2 18:43:32.867976 env[1161]: time="2023-10-02T18:43:32.867927404Z" level=info msg="starting containerd" revision=92b3a9d6f1b3bcc6dc74875cfdea653fe39f09c2 version=1.6.16 Oct 2 18:43:32.884656 env[1161]: time="2023-10-02T18:43:32.884611084Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Oct 2 18:43:32.884772 env[1161]: time="2023-10-02T18:43:32.884752244Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Oct 2 18:43:32.886166 env[1161]: time="2023-10-02T18:43:32.886130924Z" 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:43:32.886166 env[1161]: time="2023-10-02T18:43:32.886163324Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Oct 2 18:43:32.886411 env[1161]: time="2023-10-02T18:43:32.886386644Z" 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:43:32.886450 env[1161]: time="2023-10-02T18:43:32.886410284Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Oct 2 18:43:32.886450 env[1161]: time="2023-10-02T18:43:32.886423164Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" Oct 2 18:43:32.886450 env[1161]: time="2023-10-02T18:43:32.886432364Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Oct 2 18:43:32.886527 env[1161]: time="2023-10-02T18:43:32.886509164Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Oct 2 18:43:32.886760 env[1161]: time="2023-10-02T18:43:32.886739004Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Oct 2 18:43:32.886913 env[1161]: time="2023-10-02T18:43:32.886890364Z" 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:43:32.886913 env[1161]: time="2023-10-02T18:43:32.886911204Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Oct 2 18:43:32.886993 env[1161]: time="2023-10-02T18:43:32.886968844Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" Oct 2 18:43:32.886993 env[1161]: time="2023-10-02T18:43:32.886991124Z" level=info msg="metadata content store policy set" policy=shared Oct 2 18:43:32.890922 env[1161]: time="2023-10-02T18:43:32.890894084Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Oct 2 18:43:32.890963 env[1161]: time="2023-10-02T18:43:32.890929924Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Oct 2 18:43:32.890963 env[1161]: time="2023-10-02T18:43:32.890946404Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Oct 2 18:43:32.891006 env[1161]: time="2023-10-02T18:43:32.890979964Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Oct 2 18:43:32.891024 env[1161]: time="2023-10-02T18:43:32.891012484Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Oct 2 18:43:32.891043 env[1161]: time="2023-10-02T18:43:32.891026324Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Oct 2 18:43:32.891043 env[1161]: time="2023-10-02T18:43:32.891040324Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Oct 2 18:43:32.891411 env[1161]: time="2023-10-02T18:43:32.891391164Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Oct 2 18:43:32.891440 env[1161]: time="2023-10-02T18:43:32.891420684Z" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1 Oct 2 18:43:32.891440 env[1161]: time="2023-10-02T18:43:32.891434204Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Oct 2 18:43:32.891474 env[1161]: time="2023-10-02T18:43:32.891445524Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Oct 2 18:43:32.891474 env[1161]: time="2023-10-02T18:43:32.891457244Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Oct 2 18:43:32.891590 env[1161]: time="2023-10-02T18:43:32.891570924Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Oct 2 18:43:32.891669 env[1161]: time="2023-10-02T18:43:32.891652644Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Oct 2 18:43:32.891912 env[1161]: time="2023-10-02T18:43:32.891893004Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Oct 2 18:43:32.891942 env[1161]: time="2023-10-02T18:43:32.891932404Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Oct 2 18:43:32.891962 env[1161]: time="2023-10-02T18:43:32.891947644Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Oct 2 18:43:32.892100 env[1161]: time="2023-10-02T18:43:32.892086084Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Oct 2 18:43:32.892134 env[1161]: time="2023-10-02T18:43:32.892102084Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Oct 2 18:43:32.892134 env[1161]: time="2023-10-02T18:43:32.892114124Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Oct 2 18:43:32.892134 env[1161]: time="2023-10-02T18:43:32.892124644Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Oct 2 18:43:32.892184 env[1161]: time="2023-10-02T18:43:32.892136204Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Oct 2 18:43:32.892184 env[1161]: time="2023-10-02T18:43:32.892148484Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Oct 2 18:43:32.892184 env[1161]: time="2023-10-02T18:43:32.892160404Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Oct 2 18:43:32.892244 env[1161]: time="2023-10-02T18:43:32.892183324Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Oct 2 18:43:32.892244 env[1161]: time="2023-10-02T18:43:32.892196564Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Oct 2 18:43:32.892364 env[1161]: time="2023-10-02T18:43:32.892343564Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Oct 2 18:43:32.892391 env[1161]: time="2023-10-02T18:43:32.892370964Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Oct 2 18:43:32.892391 env[1161]: time="2023-10-02T18:43:32.892387084Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Oct 2 18:43:32.892428 env[1161]: time="2023-10-02T18:43:32.892399524Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Oct 2 18:43:32.892428 env[1161]: time="2023-10-02T18:43:32.892413404Z" 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:43:32.892428 env[1161]: time="2023-10-02T18:43:32.892424244Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Oct 2 18:43:32.892486 env[1161]: time="2023-10-02T18:43:32.892440084Z" level=error msg="failed to initialize a tracing processor \"otlp\"" error="no OpenTelemetry endpoint: skip plugin" Oct 2 18:43:32.892486 env[1161]: time="2023-10-02T18:43:32.892471564Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Oct 2 18:43:32.893492 env[1161]: time="2023-10-02T18:43:32.893433764Z" 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:43:32.894214 env[1161]: time="2023-10-02T18:43:32.893504644Z" level=info msg="Connect containerd service" Oct 2 18:43:32.894214 env[1161]: time="2023-10-02T18:43:32.893540524Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Oct 2 18:43:32.894932 env[1161]: time="2023-10-02T18:43:32.894904724Z" 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:43:32.895415 env[1161]: time="2023-10-02T18:43:32.895377724Z" level=info msg="Start subscribing containerd event" Oct 2 18:43:32.895454 env[1161]: time="2023-10-02T18:43:32.895434084Z" level=info msg="Start recovering state" Oct 2 18:43:32.895545 env[1161]: time="2023-10-02T18:43:32.895523204Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Oct 2 18:43:32.895578 env[1161]: time="2023-10-02T18:43:32.895558684Z" level=info msg="Start event monitor" Oct 2 18:43:32.895639 env[1161]: time="2023-10-02T18:43:32.895595564Z" level=info msg="Start snapshots syncer" Oct 2 18:43:32.895672 env[1161]: time="2023-10-02T18:43:32.895647124Z" level=info msg="Start cni network conf syncer for default" Oct 2 18:43:32.895672 env[1161]: time="2023-10-02T18:43:32.895661564Z" level=info msg="Start streaming server" Oct 2 18:43:32.895672 env[1161]: time="2023-10-02T18:43:32.895663724Z" level=info msg=serving... address=/run/containerd/containerd.sock Oct 2 18:43:32.895733 env[1161]: time="2023-10-02T18:43:32.895713924Z" level=info msg="containerd successfully booted in 0.029288s" Oct 2 18:43:32.895793 systemd[1]: Started containerd.service. Oct 2 18:43:32.898241 locksmithd[1193]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 2 18:43:33.379476 systemd-networkd[1052]: eth0: Gained IPv6LL Oct 2 18:43:33.993512 sshd_keygen[1163]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Oct 2 18:43:34.014776 systemd[1]: Finished sshd-keygen.service. Oct 2 18:43:34.016848 systemd[1]: Starting issuegen.service... Oct 2 18:43:34.022917 systemd[1]: issuegen.service: Deactivated successfully. Oct 2 18:43:34.023064 systemd[1]: Finished issuegen.service. Oct 2 18:43:34.024980 systemd[1]: Starting systemd-user-sessions.service... Oct 2 18:43:34.031752 systemd[1]: Finished systemd-user-sessions.service. Oct 2 18:43:34.033701 systemd[1]: Started getty@tty1.service. Oct 2 18:43:34.035567 systemd[1]: Started serial-getty@ttyAMA0.service. Oct 2 18:43:34.036373 systemd[1]: Reached target getty.target. Oct 2 18:43:34.036982 systemd[1]: Reached target multi-user.target. Oct 2 18:43:34.038755 systemd[1]: Starting systemd-update-utmp-runlevel.service... Oct 2 18:43:34.045950 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 2 18:43:34.046096 systemd[1]: Finished systemd-update-utmp-runlevel.service. Oct 2 18:43:34.046911 systemd[1]: Startup finished in 578ms (kernel) + 3.428s (initrd) + 4.500s (userspace) = 8.506s. Oct 2 18:43:38.510491 systemd[1]: Created slice system-sshd.slice. Oct 2 18:43:38.511524 systemd[1]: Started sshd@0-10.0.0.10:22-10.0.0.1:59856.service. Oct 2 18:43:38.593091 sshd[1218]: Accepted publickey for core from 10.0.0.1 port 59856 ssh2: RSA SHA256:327EISj6dhgnnLT6sEqi2+uwythtGn0QzwGU+yMaXG4 Oct 2 18:43:38.595849 sshd[1218]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 2 18:43:38.605479 systemd[1]: Created slice user-500.slice. Oct 2 18:43:38.611189 systemd[1]: Starting user-runtime-dir@500.service... Oct 2 18:43:38.613294 systemd-logind[1152]: New session 1 of user core. Oct 2 18:43:38.624670 systemd[1]: Finished user-runtime-dir@500.service. Oct 2 18:43:38.626210 systemd[1]: Starting user@500.service... Oct 2 18:43:38.629645 (systemd)[1221]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 2 18:43:38.692941 systemd[1221]: Queued start job for default target default.target. Oct 2 18:43:38.693456 systemd[1221]: Reached target paths.target. Oct 2 18:43:38.693475 systemd[1221]: Reached target sockets.target. Oct 2 18:43:38.693487 systemd[1221]: Reached target timers.target. Oct 2 18:43:38.693497 systemd[1221]: Reached target basic.target. Oct 2 18:43:38.693546 systemd[1221]: Reached target default.target. Oct 2 18:43:38.693569 systemd[1221]: Startup finished in 57ms. Oct 2 18:43:38.693770 systemd[1]: Started user@500.service. Oct 2 18:43:38.694760 systemd[1]: Started session-1.scope. Oct 2 18:43:38.748241 systemd[1]: Started sshd@1-10.0.0.10:22-10.0.0.1:59866.service. Oct 2 18:43:38.801713 sshd[1230]: Accepted publickey for core from 10.0.0.1 port 59866 ssh2: RSA SHA256:327EISj6dhgnnLT6sEqi2+uwythtGn0QzwGU+yMaXG4 Oct 2 18:43:38.803386 sshd[1230]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 2 18:43:38.806706 systemd-logind[1152]: New session 2 of user core. Oct 2 18:43:38.807573 systemd[1]: Started session-2.scope. Oct 2 18:43:38.864177 sshd[1230]: pam_unix(sshd:session): session closed for user core Oct 2 18:43:38.866816 systemd[1]: sshd@1-10.0.0.10:22-10.0.0.1:59866.service: Deactivated successfully. Oct 2 18:43:38.867500 systemd[1]: session-2.scope: Deactivated successfully. Oct 2 18:43:38.868001 systemd-logind[1152]: Session 2 logged out. Waiting for processes to exit. Oct 2 18:43:38.869460 systemd[1]: Started sshd@2-10.0.0.10:22-10.0.0.1:59874.service. Oct 2 18:43:38.870140 systemd-logind[1152]: Removed session 2. Oct 2 18:43:38.911633 sshd[1236]: Accepted publickey for core from 10.0.0.1 port 59874 ssh2: RSA SHA256:327EISj6dhgnnLT6sEqi2+uwythtGn0QzwGU+yMaXG4 Oct 2 18:43:38.913177 sshd[1236]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 2 18:43:38.916620 systemd-logind[1152]: New session 3 of user core. Oct 2 18:43:38.917557 systemd[1]: Started session-3.scope. Oct 2 18:43:38.969312 sshd[1236]: pam_unix(sshd:session): session closed for user core Oct 2 18:43:38.973301 systemd[1]: Started sshd@3-10.0.0.10:22-10.0.0.1:59888.service. Oct 2 18:43:38.973750 systemd[1]: sshd@2-10.0.0.10:22-10.0.0.1:59874.service: Deactivated successfully. Oct 2 18:43:38.974466 systemd[1]: session-3.scope: Deactivated successfully. Oct 2 18:43:38.975381 systemd-logind[1152]: Session 3 logged out. Waiting for processes to exit. Oct 2 18:43:38.976466 systemd-logind[1152]: Removed session 3. Oct 2 18:43:39.027405 sshd[1242]: Accepted publickey for core from 10.0.0.1 port 59888 ssh2: RSA SHA256:327EISj6dhgnnLT6sEqi2+uwythtGn0QzwGU+yMaXG4 Oct 2 18:43:39.029262 sshd[1242]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 2 18:43:39.041154 systemd-logind[1152]: New session 4 of user core. Oct 2 18:43:39.041519 systemd[1]: Started session-4.scope. Oct 2 18:43:39.098115 sshd[1242]: pam_unix(sshd:session): session closed for user core Oct 2 18:43:39.101862 systemd[1]: Started sshd@4-10.0.0.10:22-10.0.0.1:59896.service. Oct 2 18:43:39.104464 systemd-logind[1152]: Session 4 logged out. Waiting for processes to exit. Oct 2 18:43:39.104668 systemd[1]: sshd@3-10.0.0.10:22-10.0.0.1:59888.service: Deactivated successfully. Oct 2 18:43:39.105333 systemd[1]: session-4.scope: Deactivated successfully. Oct 2 18:43:39.105950 systemd-logind[1152]: Removed session 4. Oct 2 18:43:39.158205 sshd[1248]: Accepted publickey for core from 10.0.0.1 port 59896 ssh2: RSA SHA256:327EISj6dhgnnLT6sEqi2+uwythtGn0QzwGU+yMaXG4 Oct 2 18:43:39.159579 sshd[1248]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 2 18:43:39.162908 systemd-logind[1152]: New session 5 of user core. Oct 2 18:43:39.163738 systemd[1]: Started session-5.scope. Oct 2 18:43:39.228363 sudo[1253]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Oct 2 18:43:39.228852 sudo[1253]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 2 18:43:39.239459 dbus-daemon[1144]: avc: received setenforce notice (enforcing=1) Oct 2 18:43:39.241266 sudo[1253]: pam_unix(sudo:session): session closed for user root Oct 2 18:43:39.244198 sshd[1248]: pam_unix(sshd:session): session closed for user core Oct 2 18:43:39.248474 systemd[1]: Started sshd@5-10.0.0.10:22-10.0.0.1:59904.service. Oct 2 18:43:39.249013 systemd[1]: sshd@4-10.0.0.10:22-10.0.0.1:59896.service: Deactivated successfully. Oct 2 18:43:39.249722 systemd[1]: session-5.scope: Deactivated successfully. Oct 2 18:43:39.250321 systemd-logind[1152]: Session 5 logged out. Waiting for processes to exit. Oct 2 18:43:39.251520 systemd-logind[1152]: Removed session 5. Oct 2 18:43:39.320355 sshd[1256]: Accepted publickey for core from 10.0.0.1 port 59904 ssh2: RSA SHA256:327EISj6dhgnnLT6sEqi2+uwythtGn0QzwGU+yMaXG4 Oct 2 18:43:39.321975 sshd[1256]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 2 18:43:39.325238 systemd-logind[1152]: New session 6 of user core. Oct 2 18:43:39.326034 systemd[1]: Started session-6.scope. Oct 2 18:43:39.380320 sudo[1261]: 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