Sep 9 01:42:39.828618 kernel: Linux version 5.10.107-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 9.3.0-r1 p3) 9.3.0, GNU ld (Gentoo 2.36.1 p5) 2.36.1) #1 SMP Tue Mar 22 19:39:53 -00 2022 Sep 9 01:42:39.828634 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 verity.usrhash=15990f5bb88d618f1948b7e8a720f1d5b95900cfecbef00df890bd0a136b39aa Sep 9 01:42:39.828641 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Sep 9 01:42:39.828645 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Sep 9 01:42:39.828649 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Sep 9 01:42:39.828652 kernel: x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask' Sep 9 01:42:39.828657 kernel: x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256' Sep 9 01:42:39.828661 kernel: x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256' Sep 9 01:42:39.828666 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Sep 9 01:42:39.828670 kernel: x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64 Sep 9 01:42:39.828673 kernel: x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512 Sep 9 01:42:39.828677 kernel: x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024 Sep 9 01:42:39.828681 kernel: x86/fpu: Enabled xstate features 0xe7, context size is 2432 bytes, using 'compacted' format. Sep 9 01:42:39.828685 kernel: BIOS-provided physical RAM map: Sep 9 01:42:39.828690 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable Sep 9 01:42:39.828695 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved Sep 9 01:42:39.828699 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved Sep 9 01:42:39.828703 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000009cfdbfff] usable Sep 9 01:42:39.828707 kernel: BIOS-e820: [mem 0x000000009cfdc000-0x000000009cffffff] reserved Sep 9 01:42:39.828710 kernel: BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved Sep 9 01:42:39.828714 kernel: BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved Sep 9 01:42:39.828718 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Sep 9 01:42:39.828722 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Sep 9 01:42:39.828726 kernel: NX (Execute Disable) protection: active Sep 9 01:42:39.828730 kernel: SMBIOS 2.8 present. Sep 9 01:42:39.828734 kernel: DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.16.2-debian-1.16.2-1 04/01/2014 Sep 9 01:42:39.828739 kernel: Hypervisor detected: KVM Sep 9 01:42:39.828743 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Sep 9 01:42:39.828765 kernel: kvm-clock: cpu 0, msr 676ba001, primary cpu clock Sep 9 01:42:39.828770 kernel: kvm-clock: using sched offset of 3695097548 cycles Sep 9 01:42:39.828774 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Sep 9 01:42:39.828778 kernel: tsc: Detected 2793.438 MHz processor Sep 9 01:42:39.828782 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Sep 9 01:42:39.828787 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Sep 9 01:42:39.828791 kernel: last_pfn = 0x9cfdc max_arch_pfn = 0x10000000000 Sep 9 01:42:39.828795 kernel: MTRR default type: write-back Sep 9 01:42:39.828801 kernel: MTRR fixed ranges enabled: Sep 9 01:42:39.828805 kernel: 00000-9FFFF write-back Sep 9 01:42:39.828809 kernel: A0000-BFFFF uncachable Sep 9 01:42:39.828813 kernel: C0000-FFFFF write-protect Sep 9 01:42:39.828819 kernel: MTRR variable ranges enabled: Sep 9 01:42:39.828824 kernel: 0 base 0000C0000000 mask 3FFFC0000000 uncachable Sep 9 01:42:39.828828 kernel: 1 disabled Sep 9 01:42:39.828832 kernel: 2 disabled Sep 9 01:42:39.828837 kernel: 3 disabled Sep 9 01:42:39.828842 kernel: 4 disabled Sep 9 01:42:39.828846 kernel: 5 disabled Sep 9 01:42:39.828850 kernel: 6 disabled Sep 9 01:42:39.828854 kernel: 7 disabled Sep 9 01:42:39.828858 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Sep 9 01:42:39.828863 kernel: Using GB pages for direct mapping Sep 9 01:42:39.828867 kernel: ACPI: Early table checksum verification disabled Sep 9 01:42:39.828872 kernel: ACPI: RSDP 0x00000000000F59D0 000014 (v00 BOCHS ) Sep 9 01:42:39.828876 kernel: ACPI: RSDT 0x000000009CFE241A 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 9 01:42:39.828880 kernel: ACPI: FACP 0x000000009CFE21FA 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) Sep 9 01:42:39.828886 kernel: ACPI: DSDT 0x000000009CFE0040 0021BA (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 9 01:42:39.828890 kernel: ACPI: FACS 0x000000009CFE0000 000040 Sep 9 01:42:39.828895 kernel: ACPI: APIC 0x000000009CFE22EE 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 9 01:42:39.828899 kernel: ACPI: HPET 0x000000009CFE237E 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 9 01:42:39.828904 kernel: ACPI: MCFG 0x000000009CFE23B6 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 9 01:42:39.828908 kernel: ACPI: WAET 0x000000009CFE23F2 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 9 01:42:39.828912 kernel: ACPI: Reserving FACP table memory at [mem 0x9cfe21fa-0x9cfe22ed] Sep 9 01:42:39.828918 kernel: ACPI: Reserving DSDT table memory at [mem 0x9cfe0040-0x9cfe21f9] Sep 9 01:42:39.828922 kernel: ACPI: Reserving FACS table memory at [mem 0x9cfe0000-0x9cfe003f] Sep 9 01:42:39.828926 kernel: ACPI: Reserving APIC table memory at [mem 0x9cfe22ee-0x9cfe237d] Sep 9 01:42:39.828930 kernel: ACPI: Reserving HPET table memory at [mem 0x9cfe237e-0x9cfe23b5] Sep 9 01:42:39.828934 kernel: ACPI: Reserving MCFG table memory at [mem 0x9cfe23b6-0x9cfe23f1] Sep 9 01:42:39.828939 kernel: ACPI: Reserving WAET table memory at [mem 0x9cfe23f2-0x9cfe2419] Sep 9 01:42:39.828943 kernel: ACPI: Local APIC address 0xfee00000 Sep 9 01:42:39.828947 kernel: No NUMA configuration found Sep 9 01:42:39.828952 kernel: Faking a node at [mem 0x0000000000000000-0x000000009cfdbfff] Sep 9 01:42:39.828956 kernel: NODE_DATA(0) allocated [mem 0x9cfd6000-0x9cfdbfff] Sep 9 01:42:39.828961 kernel: Zone ranges: Sep 9 01:42:39.828965 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Sep 9 01:42:39.828970 kernel: DMA32 [mem 0x0000000001000000-0x000000009cfdbfff] Sep 9 01:42:39.828974 kernel: Normal empty Sep 9 01:42:39.828978 kernel: Movable zone start for each node Sep 9 01:42:39.828982 kernel: Early memory node ranges Sep 9 01:42:39.828986 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] Sep 9 01:42:39.828991 kernel: node 0: [mem 0x0000000000100000-0x000000009cfdbfff] Sep 9 01:42:39.828995 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000009cfdbfff] Sep 9 01:42:39.829001 kernel: On node 0 totalpages: 642938 Sep 9 01:42:39.829005 kernel: DMA zone: 64 pages used for memmap Sep 9 01:42:39.829009 kernel: DMA zone: 21 pages reserved Sep 9 01:42:39.829013 kernel: DMA zone: 3998 pages, LIFO batch:0 Sep 9 01:42:39.829017 kernel: DMA32 zone: 9984 pages used for memmap Sep 9 01:42:39.829021 kernel: DMA32 zone: 638940 pages, LIFO batch:63 Sep 9 01:42:39.829026 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Sep 9 01:42:39.829030 kernel: On node 0, zone DMA: 97 pages in unavailable ranges Sep 9 01:42:39.829034 kernel: On node 0, zone DMA32: 12324 pages in unavailable ranges Sep 9 01:42:39.829038 kernel: ACPI: PM-Timer IO Port: 0x608 Sep 9 01:42:39.829044 kernel: ACPI: Local APIC address 0xfee00000 Sep 9 01:42:39.829048 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Sep 9 01:42:39.829052 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Sep 9 01:42:39.829056 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Sep 9 01:42:39.829061 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Sep 9 01:42:39.829065 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Sep 9 01:42:39.829069 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Sep 9 01:42:39.829074 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Sep 9 01:42:39.829078 kernel: ACPI: IRQ0 used by override. Sep 9 01:42:39.829082 kernel: ACPI: IRQ5 used by override. Sep 9 01:42:39.829087 kernel: ACPI: IRQ9 used by override. Sep 9 01:42:39.829092 kernel: ACPI: IRQ10 used by override. Sep 9 01:42:39.829096 kernel: ACPI: IRQ11 used by override. Sep 9 01:42:39.829100 kernel: Using ACPI (MADT) for SMP configuration information Sep 9 01:42:39.829104 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Sep 9 01:42:39.829108 kernel: TSC deadline timer available Sep 9 01:42:39.829112 kernel: smpboot: Allowing 4 CPUs, 0 hotplug CPUs Sep 9 01:42:39.829117 kernel: kvm-guest: KVM setup pv remote TLB flush Sep 9 01:42:39.829121 kernel: kvm-guest: setup PV sched yield Sep 9 01:42:39.829125 kernel: [mem 0xc0000000-0xfed1bfff] available for PCI devices Sep 9 01:42:39.829131 kernel: Booting paravirtualized kernel on KVM Sep 9 01:42:39.829135 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Sep 9 01:42:39.829140 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:512 nr_cpu_ids:4 nr_node_ids:1 Sep 9 01:42:39.829144 kernel: percpu: Embedded 58 pages/cpu s199704 r8192 d29672 u524288 Sep 9 01:42:39.829148 kernel: pcpu-alloc: s199704 r8192 d29672 u524288 alloc=1*2097152 Sep 9 01:42:39.829153 kernel: pcpu-alloc: [0] 0 1 2 3 Sep 9 01:42:39.829157 kernel: kvm-guest: KVM setup async PF for cpu 0 Sep 9 01:42:39.829166 kernel: kvm-guest: stealtime: cpu 0, msr 9a41c580 Sep 9 01:42:39.829172 kernel: kvm-guest: PV spinlocks enabled Sep 9 01:42:39.829176 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Sep 9 01:42:39.829181 kernel: Built 1 zonelists, mobility grouping on. Total pages: 632869 Sep 9 01:42:39.829185 kernel: Policy zone: DMA32 Sep 9 01:42:39.829190 kernel: Kernel command line: rootflags=rw mount.usrflags=ro 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 verity.usrhash=15990f5bb88d618f1948b7e8a720f1d5b95900cfecbef00df890bd0a136b39aa Sep 9 01:42:39.829195 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 9 01:42:39.829200 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 9 01:42:39.829204 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 9 01:42:39.829210 kernel: Memory: 2448560K/2571752K available (10246K kernel code, 2239K rwdata, 11640K rodata, 42228K init, 1436K bss, 122988K reserved, 0K cma-reserved) Sep 9 01:42:39.829215 kernel: random: get_random_u64 called from __kmem_cache_create+0x26/0x400 with crng_init=0 Sep 9 01:42:39.829219 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Sep 9 01:42:39.829224 kernel: ftrace: allocating 34378 entries in 135 pages Sep 9 01:42:39.829228 kernel: ftrace: allocated 135 pages with 4 groups Sep 9 01:42:39.829233 kernel: rcu: Hierarchical RCU implementation. Sep 9 01:42:39.829238 kernel: rcu: RCU event tracing is enabled. Sep 9 01:42:39.829242 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Sep 9 01:42:39.829247 kernel: Rude variant of Tasks RCU enabled. Sep 9 01:42:39.829252 kernel: Tracing variant of Tasks RCU enabled. Sep 9 01:42:39.829257 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 9 01:42:39.829261 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Sep 9 01:42:39.829266 kernel: NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16 Sep 9 01:42:39.829270 kernel: Console: colour VGA+ 80x25 Sep 9 01:42:39.829275 kernel: printk: console [ttyS0] enabled Sep 9 01:42:39.829279 kernel: ACPI: Core revision 20200925 Sep 9 01:42:39.829284 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Sep 9 01:42:39.829288 kernel: APIC: Switch to symmetric I/O mode setup Sep 9 01:42:39.829294 kernel: x2apic enabled Sep 9 01:42:39.829299 kernel: Switched APIC routing to physical x2apic. Sep 9 01:42:39.829303 kernel: kvm-guest: setup PV IPIs Sep 9 01:42:39.829307 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Sep 9 01:42:39.829312 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x284409db922, max_idle_ns: 440795228871 ns Sep 9 01:42:39.829316 kernel: Calibrating delay loop (skipped) preset value.. 5586.87 BogoMIPS (lpj=2793438) Sep 9 01:42:39.829321 kernel: pid_max: default: 32768 minimum: 301 Sep 9 01:42:39.829326 kernel: LSM: Security Framework initializing Sep 9 01:42:39.829332 kernel: SELinux: Initializing. Sep 9 01:42:39.829336 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 9 01:42:39.829341 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 9 01:42:39.829345 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated Sep 9 01:42:39.829349 kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 Sep 9 01:42:39.829354 kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 Sep 9 01:42:39.829358 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Sep 9 01:42:39.829364 kernel: Spectre V2 : Mitigation: Retpolines Sep 9 01:42:39.829368 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch Sep 9 01:42:39.829373 kernel: Speculative Store Bypass: Vulnerable Sep 9 01:42:39.829377 kernel: Freeing SMP alternatives memory: 28K Sep 9 01:42:39.829381 kernel: smpboot: CPU0: Intel(R) Xeon(R) Platinum 8370C CPU @ 2.80GHz (family: 0x6, model: 0x6a, stepping: 0x6) Sep 9 01:42:39.829386 kernel: Performance Events: unsupported p6 CPU model 106 no PMU driver, software events only. Sep 9 01:42:39.829391 kernel: rcu: Hierarchical SRCU implementation. Sep 9 01:42:39.829395 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Sep 9 01:42:39.829400 kernel: smp: Bringing up secondary CPUs ... Sep 9 01:42:39.829405 kernel: x86: Booting SMP configuration: Sep 9 01:42:39.829410 kernel: .... node #0, CPUs: #1 Sep 9 01:42:39.829414 kernel: kvm-clock: cpu 1, msr 676ba041, secondary cpu clock Sep 9 01:42:39.829418 kernel: kvm-guest: KVM setup async PF for cpu 1 Sep 9 01:42:39.829423 kernel: kvm-guest: stealtime: cpu 1, msr 9a49c580 Sep 9 01:42:39.829427 kernel: #2 Sep 9 01:42:39.829432 kernel: kvm-clock: cpu 2, msr 676ba081, secondary cpu clock Sep 9 01:42:39.829436 kernel: kvm-guest: KVM setup async PF for cpu 2 Sep 9 01:42:39.829440 kernel: kvm-guest: stealtime: cpu 2, msr 9a51c580 Sep 9 01:42:39.829445 kernel: #3 Sep 9 01:42:39.829450 kernel: kvm-clock: cpu 3, msr 676ba0c1, secondary cpu clock Sep 9 01:42:39.829455 kernel: kvm-guest: KVM setup async PF for cpu 3 Sep 9 01:42:39.829459 kernel: kvm-guest: stealtime: cpu 3, msr 9a59c580 Sep 9 01:42:39.829463 kernel: smp: Brought up 1 node, 4 CPUs Sep 9 01:42:39.829468 kernel: smpboot: Max logical packages: 1 Sep 9 01:42:39.829472 kernel: smpboot: Total of 4 processors activated (22347.50 BogoMIPS) Sep 9 01:42:39.829477 kernel: devtmpfs: initialized Sep 9 01:42:39.829481 kernel: x86/mm: Memory block size: 128MB Sep 9 01:42:39.829485 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 9 01:42:39.829491 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Sep 9 01:42:39.829496 kernel: pinctrl core: initialized pinctrl subsystem Sep 9 01:42:39.829500 kernel: NET: Registered protocol family 16 Sep 9 01:42:39.829504 kernel: audit: initializing netlink subsys (disabled) Sep 9 01:42:39.829509 kernel: audit: type=2000 audit(1788918159.761:1): state=initialized audit_enabled=0 res=1 Sep 9 01:42:39.829513 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 9 01:42:39.829517 kernel: thermal_sys: Registered thermal governor 'user_space' Sep 9 01:42:39.829522 kernel: cpuidle: using governor menu Sep 9 01:42:39.829526 kernel: ACPI: bus type PCI registered Sep 9 01:42:39.829530 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 9 01:42:39.829536 kernel: dca service started, version 1.12.1 Sep 9 01:42:39.829540 kernel: PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xb0000000-0xbfffffff] (base 0xb0000000) Sep 9 01:42:39.829545 kernel: PCI: MMCONFIG at [mem 0xb0000000-0xbfffffff] reserved in E820 Sep 9 01:42:39.829549 kernel: PCI: Using configuration type 1 for base access Sep 9 01:42:39.829554 kernel: Kprobes globally optimized Sep 9 01:42:39.829558 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Sep 9 01:42:39.829574 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Sep 9 01:42:39.829579 kernel: ACPI: Added _OSI(Module Device) Sep 9 01:42:39.829584 kernel: ACPI: Added _OSI(Processor Device) Sep 9 01:42:39.829590 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Sep 9 01:42:39.829594 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 9 01:42:39.829599 kernel: ACPI: Added _OSI(Linux-Dell-Video) Sep 9 01:42:39.829603 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Sep 9 01:42:39.829607 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Sep 9 01:42:39.829612 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 9 01:42:39.829616 kernel: ACPI: Interpreter enabled Sep 9 01:42:39.829621 kernel: ACPI: (supports S0 S3 S5) Sep 9 01:42:39.829625 kernel: ACPI: Using IOAPIC for interrupt routing Sep 9 01:42:39.829630 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Sep 9 01:42:39.829636 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F Sep 9 01:42:39.829640 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Sep 9 01:42:39.829721 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Sep 9 01:42:39.829791 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] Sep 9 01:42:39.829833 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] Sep 9 01:42:39.829839 kernel: PCI host bridge to bus 0000:00 Sep 9 01:42:39.829885 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Sep 9 01:42:39.829928 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Sep 9 01:42:39.829966 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Sep 9 01:42:39.830002 kernel: pci_bus 0000:00: root bus resource [mem 0x9d000000-0xafffffff window] Sep 9 01:42:39.830039 kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] Sep 9 01:42:39.830075 kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window] Sep 9 01:42:39.830111 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Sep 9 01:42:39.830160 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 Sep 9 01:42:39.830209 kernel: pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 Sep 9 01:42:39.830252 kernel: pci 0000:00:01.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref] Sep 9 01:42:39.830293 kernel: pci 0000:00:01.0: reg 0x18: [mem 0xfebd0000-0xfebd0fff] Sep 9 01:42:39.830336 kernel: pci 0000:00:01.0: reg 0x30: [mem 0xfebc0000-0xfebcffff pref] Sep 9 01:42:39.830382 kernel: pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 Sep 9 01:42:39.830425 kernel: pci 0000:00:02.0: reg 0x10: [io 0xc0c0-0xc0df] Sep 9 01:42:39.830470 kernel: pci 0000:00:02.0: reg 0x14: [mem 0xfebd1000-0xfebd1fff] Sep 9 01:42:39.830512 kernel: pci 0000:00:02.0: reg 0x20: [mem 0xfe000000-0xfe003fff 64bit pref] Sep 9 01:42:39.830557 kernel: pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 Sep 9 01:42:39.830618 kernel: pci 0000:00:03.0: reg 0x10: [io 0xc000-0xc07f] Sep 9 01:42:39.830662 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xfebd2000-0xfebd2fff] Sep 9 01:42:39.830704 kernel: pci 0000:00:03.0: reg 0x20: [mem 0xfe004000-0xfe007fff 64bit pref] Sep 9 01:42:39.830767 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 Sep 9 01:42:39.830816 kernel: pci 0000:00:04.0: reg 0x10: [io 0xc0e0-0xc0ff] Sep 9 01:42:39.830859 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xfebd3000-0xfebd3fff] Sep 9 01:42:39.830904 kernel: pci 0000:00:04.0: reg 0x20: [mem 0xfe008000-0xfe00bfff 64bit pref] Sep 9 01:42:39.830946 kernel: pci 0000:00:04.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] Sep 9 01:42:39.830992 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 Sep 9 01:42:39.831035 kernel: pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO Sep 9 01:42:39.831078 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 Sep 9 01:42:39.831120 kernel: pci 0000:00:1f.2: reg 0x20: [io 0xc100-0xc11f] Sep 9 01:42:39.831161 kernel: pci 0000:00:1f.2: reg 0x24: [mem 0xfebd4000-0xfebd4fff] Sep 9 01:42:39.831205 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 Sep 9 01:42:39.831247 kernel: pci 0000:00:1f.3: reg 0x20: [io 0x0700-0x073f] Sep 9 01:42:39.831252 kernel: ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11) Sep 9 01:42:39.831257 kernel: ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11) Sep 9 01:42:39.831261 kernel: ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11) Sep 9 01:42:39.831266 kernel: ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11) Sep 9 01:42:39.831272 kernel: ACPI: PCI Interrupt Link [LNKE] (IRQs 5 *10 11) Sep 9 01:42:39.831276 kernel: ACPI: PCI Interrupt Link [LNKF] (IRQs 5 *10 11) Sep 9 01:42:39.831281 kernel: ACPI: PCI Interrupt Link [LNKG] (IRQs 5 10 *11) Sep 9 01:42:39.831285 kernel: ACPI: PCI Interrupt Link [LNKH] (IRQs 5 10 *11) Sep 9 01:42:39.831290 kernel: ACPI: PCI Interrupt Link [GSIA] (IRQs *16) Sep 9 01:42:39.831294 kernel: ACPI: PCI Interrupt Link [GSIB] (IRQs *17) Sep 9 01:42:39.831298 kernel: ACPI: PCI Interrupt Link [GSIC] (IRQs *18) Sep 9 01:42:39.831303 kernel: ACPI: PCI Interrupt Link [GSID] (IRQs *19) Sep 9 01:42:39.831307 kernel: ACPI: PCI Interrupt Link [GSIE] (IRQs *20) Sep 9 01:42:39.831313 kernel: ACPI: PCI Interrupt Link [GSIF] (IRQs *21) Sep 9 01:42:39.831317 kernel: ACPI: PCI Interrupt Link [GSIG] (IRQs *22) Sep 9 01:42:39.831322 kernel: ACPI: PCI Interrupt Link [GSIH] (IRQs *23) Sep 9 01:42:39.831326 kernel: iommu: Default domain type: Translated Sep 9 01:42:39.831367 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device Sep 9 01:42:39.831408 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Sep 9 01:42:39.831448 kernel: pci 0000:00:01.0: vgaarb: bridge control possible Sep 9 01:42:39.831454 kernel: vgaarb: loaded Sep 9 01:42:39.831458 kernel: PCI: Using ACPI for IRQ routing Sep 9 01:42:39.831464 kernel: PCI: pci_cache_line_size set to 64 bytes Sep 9 01:42:39.831469 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] Sep 9 01:42:39.831473 kernel: e820: reserve RAM buffer [mem 0x9cfdc000-0x9fffffff] Sep 9 01:42:39.831478 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Sep 9 01:42:39.831482 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Sep 9 01:42:39.831487 kernel: clocksource: Switched to clocksource kvm-clock Sep 9 01:42:39.831491 kernel: VFS: Disk quotas dquot_6.6.0 Sep 9 01:42:39.831496 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 9 01:42:39.831500 kernel: pnp: PnP ACPI init Sep 9 01:42:39.831549 kernel: pnp 00:00: Plug and Play ACPI device, IDs PNP0303 (active) Sep 9 01:42:39.831608 kernel: pnp 00:01: Plug and Play ACPI device, IDs PNP0f13 (active) Sep 9 01:42:39.831653 kernel: pnp 00:02: Plug and Play ACPI device, IDs PNP0400 (active) Sep 9 01:42:39.831695 kernel: pnp 00:03: Plug and Play ACPI device, IDs PNP0501 (active) Sep 9 01:42:39.831737 kernel: pnp 00:04: Plug and Play ACPI device, IDs PNP0b00 (active) Sep 9 01:42:39.831799 kernel: system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved Sep 9 01:42:39.831841 kernel: system 00:05: Plug and Play ACPI device, IDs PNP0c01 (active) Sep 9 01:42:39.831847 kernel: pnp: PnP ACPI: found 6 devices Sep 9 01:42:39.831852 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Sep 9 01:42:39.831857 kernel: NET: Registered protocol family 2 Sep 9 01:42:39.831861 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Sep 9 01:42:39.831866 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Sep 9 01:42:39.831870 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Sep 9 01:42:39.831875 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Sep 9 01:42:39.831881 kernel: TCP: Hash tables configured (established 32768 bind 32768) Sep 9 01:42:39.831886 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 9 01:42:39.831890 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 9 01:42:39.831895 kernel: NET: Registered protocol family 1 Sep 9 01:42:39.831899 kernel: NET: Registered protocol family 44 Sep 9 01:42:39.831938 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Sep 9 01:42:39.831975 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Sep 9 01:42:39.832011 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Sep 9 01:42:39.832050 kernel: pci_bus 0000:00: resource 7 [mem 0x9d000000-0xafffffff window] Sep 9 01:42:39.832086 kernel: pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] Sep 9 01:42:39.832124 kernel: pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window] Sep 9 01:42:39.832166 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Sep 9 01:42:39.832172 kernel: PCI: CLS 0 bytes, default 64 Sep 9 01:42:39.832177 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Sep 9 01:42:39.832182 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x284409db922, max_idle_ns: 440795228871 ns Sep 9 01:42:39.832186 kernel: Initialise system trusted keyrings Sep 9 01:42:39.832191 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Sep 9 01:42:39.832197 kernel: Key type asymmetric registered Sep 9 01:42:39.832201 kernel: Asymmetric key parser 'x509' registered Sep 9 01:42:39.832206 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Sep 9 01:42:39.832210 kernel: io scheduler mq-deadline registered Sep 9 01:42:39.832215 kernel: io scheduler kyber registered Sep 9 01:42:39.832219 kernel: io scheduler bfq registered Sep 9 01:42:39.832224 kernel: intel_idle: Please enable MWAIT in BIOS SETUP Sep 9 01:42:39.832228 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Sep 9 01:42:39.832233 kernel: PCI Interrupt Link [GSIG] enabled at IRQ 22 Sep 9 01:42:39.832238 kernel: PCI Interrupt Link [GSIH] enabled at IRQ 23 Sep 9 01:42:39.832243 kernel: PCI Interrupt Link [GSIE] enabled at IRQ 20 Sep 9 01:42:39.832247 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 9 01:42:39.832252 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Sep 9 01:42:39.832257 kernel: wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information. Sep 9 01:42:39.832261 kernel: random: fast init done Sep 9 01:42:39.832266 kernel: wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld . All Rights Reserved. Sep 9 01:42:39.832270 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Sep 9 01:42:39.832275 kernel: random: crng init done Sep 9 01:42:39.832279 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Sep 9 01:42:39.832285 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Sep 9 01:42:39.832290 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Sep 9 01:42:39.832327 kernel: rtc_cmos 00:04: RTC can wake from S4 Sep 9 01:42:39.832364 kernel: rtc_cmos 00:04: registered as rtc0 Sep 9 01:42:39.832401 kernel: rtc_cmos 00:04: setting system clock to 2026-09-09T01:42:39 UTC (1788918159) Sep 9 01:42:39.832438 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs Sep 9 01:42:39.832444 kernel: intel_pstate: CPU model not supported Sep 9 01:42:39.832449 kernel: NET: Registered protocol family 10 Sep 9 01:42:39.832454 kernel: Segment Routing with IPv6 Sep 9 01:42:39.832459 kernel: NET: Registered protocol family 17 Sep 9 01:42:39.832463 kernel: Key type dns_resolver registered Sep 9 01:42:39.832468 kernel: IPI shorthand broadcast: enabled Sep 9 01:42:39.832472 kernel: sched_clock: Marking stable (949778087, 227611712)->(1225457487, -48067688) Sep 9 01:42:39.832477 kernel: registered taskstats version 1 Sep 9 01:42:39.832481 kernel: Loading compiled-in X.509 certificates Sep 9 01:42:39.832486 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.10.107-flatcar: 5866c780451a04cc08b278527846e2b48fb1aa10' Sep 9 01:42:39.832498 kernel: Key type ._fscrypt registered Sep 9 01:42:39.832505 kernel: Key type .fscrypt registered Sep 9 01:42:39.832510 kernel: Key type fscrypt-provisioning registered Sep 9 01:42:39.832515 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 9 01:42:39.832520 kernel: ima: Allocated hash algorithm: sha1 Sep 9 01:42:39.832526 kernel: ima: No architecture policies found Sep 9 01:42:39.832531 kernel: Freeing unused kernel image (initmem) memory: 42228K Sep 9 01:42:39.832536 kernel: Write protecting the kernel read-only data: 24576k Sep 9 01:42:39.832540 kernel: Freeing unused kernel image (text/rodata gap) memory: 2040K Sep 9 01:42:39.832545 kernel: Freeing unused kernel image (rodata/data gap) memory: 648K Sep 9 01:42:39.832550 kernel: Run /init as init process Sep 9 01:42:39.832554 kernel: with arguments: Sep 9 01:42:39.832559 kernel: /init Sep 9 01:42:39.832577 kernel: with environment: Sep 9 01:42:39.832582 kernel: HOME=/ Sep 9 01:42:39.832589 kernel: TERM=linux Sep 9 01:42:39.832593 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 9 01:42:39.832600 systemd[1]: systemd 249 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 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Sep 9 01:42:39.832607 systemd[1]: Detected virtualization kvm. Sep 9 01:42:39.832612 systemd[1]: Detected architecture x86-64. Sep 9 01:42:39.832617 systemd[1]: Running in initial RAM disk. Sep 9 01:42:39.832622 systemd[1]: No hostname configured, using default hostname. Sep 9 01:42:39.832628 systemd[1]: Hostname set to . Sep 9 01:42:39.832633 systemd[1]: Initializing machine ID from VM UUID. Sep 9 01:42:39.832638 systemd[1]: Queued start job for default target Initrd Default Target. Sep 9 01:42:39.832643 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Sep 9 01:42:39.832648 systemd[1]: Reached target Local Encrypted Volumes. Sep 9 01:42:39.832653 systemd[1]: Reached target Path Units. Sep 9 01:42:39.832658 systemd[1]: Reached target Slice Units. Sep 9 01:42:39.832663 systemd[1]: Reached target Swaps. Sep 9 01:42:39.832668 systemd[1]: Reached target Timer Units. Sep 9 01:42:39.832674 systemd[1]: Listening on Open-iSCSI iscsid Socket. Sep 9 01:42:39.832679 systemd[1]: Listening on Open-iSCSI iscsiuio Socket. Sep 9 01:42:39.832684 systemd[1]: Listening on Journal Audit Socket. Sep 9 01:42:39.832689 systemd[1]: Listening on Journal Socket (/dev/log). Sep 9 01:42:39.832694 systemd[1]: Listening on Journal Socket. Sep 9 01:42:39.832699 systemd[1]: Listening on Network Service Netlink Socket. Sep 9 01:42:39.832704 systemd[1]: Listening on udev Control Socket. Sep 9 01:42:39.832709 systemd[1]: Listening on udev Kernel Socket. Sep 9 01:42:39.832715 systemd[1]: Reached target Socket Units. Sep 9 01:42:39.832720 systemd[1]: Starting Create List of Static Device Nodes... Sep 9 01:42:39.832725 systemd[1]: Finished Network Cleanup. Sep 9 01:42:39.832730 systemd[1]: Started Hardware RNG Entropy Gatherer Daemon. Sep 9 01:42:39.832734 systemd[1]: Starting Journal Service... Sep 9 01:42:39.832739 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Sep 9 01:42:39.832744 systemd[1]: Starting Apply Kernel Variables... Sep 9 01:42:39.832766 systemd[1]: Starting Setup Virtual Console... Sep 9 01:42:39.832772 systemd[1]: Finished Create List of Static Device Nodes. Sep 9 01:42:39.832781 systemd-journald[190]: Journal started Sep 9 01:42:39.832809 systemd-journald[190]: Runtime Journal (/run/log/journal/ad9198a0e79144b7bd07e4563d4ce0b7) is 6.0M, max 48.7M, 42.6M free. Sep 9 01:42:39.920000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:39.925789 kernel: audit: type=1130 audit(1788918159.920:2): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:39.925820 systemd[1]: Started Journal Service. Sep 9 01:42:39.932000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:39.933986 systemd[1]: Finished Apply Kernel Variables. Sep 9 01:42:39.939825 kernel: audit: type=1130 audit(1788918159.932:3): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:39.953000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:39.954734 systemd[1]: Finished Setup Virtual Console. Sep 9 01:42:39.974088 kernel: audit: type=1130 audit(1788918159.953:4): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:39.974120 kernel: audit: type=1130 audit(1788918159.962:5): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:39.962000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:39.963698 systemd[1]: Starting dracut ask for additional cmdline parameters... Sep 9 01:42:39.974797 systemd[1]: Starting Create Static Device Nodes in /dev... Sep 9 01:42:39.979796 systemd[1]: Finished Create Static Device Nodes in /dev. Sep 9 01:42:39.979000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:39.988798 kernel: audit: type=1130 audit(1788918159.979:6): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:40.000270 systemd[1]: Finished dracut ask for additional cmdline parameters. Sep 9 01:42:40.011956 kernel: audit: type=1130 audit(1788918159.999:7): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:39.999000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:40.012013 systemd[1]: Starting dracut cmdline hook... Sep 9 01:42:40.024154 dracut-cmdline[209]: dracut-dracut-053 Sep 9 01:42:40.026500 dracut-cmdline[209]: Using kernel command line parameters: rd.driver.pre=btrfs rootflags=rw mount.usrflags=ro 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 verity.usrhash=15990f5bb88d618f1948b7e8a720f1d5b95900cfecbef00df890bd0a136b39aa Sep 9 01:42:40.085791 kernel: SCSI subsystem initialized Sep 9 01:42:40.092783 kernel: Loading iSCSI transport class v2.0-870. Sep 9 01:42:40.103797 kernel: iscsi: registered transport (tcp) Sep 9 01:42:40.120524 kernel: iscsi: registered transport (qla4xxx) Sep 9 01:42:40.120589 kernel: QLogic iSCSI HBA Driver Sep 9 01:42:40.130945 systemd[1]: Finished dracut cmdline hook. Sep 9 01:42:40.138296 kernel: audit: type=1130 audit(1788918160.130:8): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:40.130000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:40.131677 systemd[1]: Starting dracut pre-udev hook... Sep 9 01:42:40.150508 kernel: device-mapper: uevent: version 1.0.3 Sep 9 01:42:40.150554 kernel: device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com Sep 9 01:42:40.196838 kernel: raid6: avx512x4 gen() 34409 MB/s Sep 9 01:42:40.214925 kernel: raid6: avx512x4 xor() 9270 MB/s Sep 9 01:42:40.232811 kernel: raid6: avx512x2 gen() 33367 MB/s Sep 9 01:42:40.250915 kernel: raid6: avx512x2 xor() 21011 MB/s Sep 9 01:42:40.268825 kernel: raid6: avx512x1 gen() 39744 MB/s Sep 9 01:42:40.286810 kernel: raid6: avx512x1 xor() 24447 MB/s Sep 9 01:42:40.304812 kernel: raid6: avx2x4 gen() 27309 MB/s Sep 9 01:42:40.322811 kernel: raid6: avx2x4 xor() 8931 MB/s Sep 9 01:42:40.340810 kernel: raid6: avx2x2 gen() 20083 MB/s Sep 9 01:42:40.358807 kernel: raid6: avx2x2 xor() 12148 MB/s Sep 9 01:42:40.376816 kernel: raid6: avx2x1 gen() 13961 MB/s Sep 9 01:42:40.394809 kernel: raid6: avx2x1 xor() 13974 MB/s Sep 9 01:42:40.412826 kernel: raid6: sse2x4 gen() 10492 MB/s Sep 9 01:42:40.430830 kernel: raid6: sse2x4 xor() 5857 MB/s Sep 9 01:42:40.448827 kernel: raid6: sse2x2 gen() 10082 MB/s Sep 9 01:42:40.466838 kernel: raid6: sse2x2 xor() 6644 MB/s Sep 9 01:42:40.484929 kernel: raid6: sse2x1 gen() 7215 MB/s Sep 9 01:42:40.503740 kernel: raid6: sse2x1 xor() 5701 MB/s Sep 9 01:42:40.503819 kernel: raid6: using algorithm avx512x1 gen() 39744 MB/s Sep 9 01:42:40.503826 kernel: raid6: .... xor() 24447 MB/s, rmw enabled Sep 9 01:42:40.505222 kernel: raid6: using avx512x2 recovery algorithm Sep 9 01:42:40.518792 kernel: xor: automatically using best checksumming function avx Sep 9 01:42:40.595796 kernel: Btrfs loaded, crc32c=crc32c-intel Sep 9 01:42:40.603503 systemd[1]: Finished dracut pre-udev hook. Sep 9 01:42:40.614279 kernel: audit: type=1130 audit(1788918160.604:9): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:40.614303 kernel: audit: type=1334 audit(1788918160.611:10): prog-id=6 op=LOAD Sep 9 01:42:40.604000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:40.611000 audit: BPF prog-id=6 op=LOAD Sep 9 01:42:40.613000 audit: BPF prog-id=7 op=LOAD Sep 9 01:42:40.613000 audit: BPF prog-id=8 op=LOAD Sep 9 01:42:40.614658 systemd[1]: Starting Rule-based Manager for Device Events and Files... Sep 9 01:42:40.627146 systemd-udevd[330]: /usr/lib64/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Sep 9 01:42:40.631000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:40.628622 systemd[1]: Started Rule-based Manager for Device Events and Files. Sep 9 01:42:40.634000 audit: BPF prog-id=9 op=LOAD Sep 9 01:42:40.632640 systemd[1]: Starting dracut pre-trigger hook... Sep 9 01:42:40.636056 systemd[1]: Starting Network Configuration... Sep 9 01:42:40.645863 dracut-pre-trigger[343]: rd.md=0: removing MD RAID activation Sep 9 01:42:40.657437 systemd-networkd[344]: lo: Link UP Sep 9 01:42:40.659000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-networkd comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:40.657455 systemd-networkd[344]: lo: Gained carrier Sep 9 01:42:40.661000 audit: BPF prog-id=10 op=LOAD Sep 9 01:42:40.657723 systemd-networkd[344]: Enumeration completed Sep 9 01:42:40.657828 systemd[1]: Started Network Configuration. Sep 9 01:42:40.662644 systemd[1]: Starting Network Name Resolution... Sep 9 01:42:40.670000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-trigger comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:40.669001 systemd[1]: Finished dracut pre-trigger hook. Sep 9 01:42:40.671635 systemd[1]: Starting Coldplug All udev Devices... Sep 9 01:42:40.678308 systemd-udevd[330]: /usr/lib64/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Sep 9 01:42:40.695346 systemd[1]: Finished Coldplug All udev Devices. Sep 9 01:42:40.696000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:40.707935 systemd-resolved[369]: Positive Trust Anchors: Sep 9 01:42:40.707958 systemd-resolved[369]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 9 01:42:40.724554 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Sep 9 01:42:40.724695 kernel: vda: detected capacity change from 0 to 4756340736 Sep 9 01:42:40.716000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:40.707990 systemd-resolved[369]: 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 Sep 9 01:42:40.739559 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 9 01:42:40.708236 systemd-resolved[369]: Defaulting to hostname 'linux'. Sep 9 01:42:40.709069 systemd[1]: Started Network Name Resolution. Sep 9 01:42:40.747618 kernel: cryptd: max_cpu_qlen set to 1000 Sep 9 01:42:40.717354 systemd[1]: Reached target Network. Sep 9 01:42:40.722349 systemd[1]: Reached target Host and Network Name Lookups. Sep 9 01:42:40.749000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsiuio comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:40.725151 systemd[1]: Starting iSCSI UserSpace I/O driver... Sep 9 01:42:40.747628 systemd[1]: Started iSCSI UserSpace I/O driver. Sep 9 01:42:40.754384 systemd[1]: Starting Open-iSCSI... Sep 9 01:42:40.766058 kernel: AVX2 version of gcm_enc/dec engaged. Sep 9 01:42:40.766084 kernel: AES CTR mode by8 optimization enabled Sep 9 01:42:40.765137 systemd-udevd[390]: Using default interface naming scheme 'v249'. Sep 9 01:42:40.766000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsid comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:40.774853 iscsid[400]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Sep 9 01:42:40.774853 iscsid[400]: 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]. Sep 9 01:42:40.774853 iscsid[400]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Sep 9 01:42:40.774853 iscsid[400]: If using hardware iscsi like qla4xxx this message can be ignored. Sep 9 01:42:40.774853 iscsid[400]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Sep 9 01:42:40.774853 iscsid[400]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Sep 9 01:42:40.918054 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/vda6 scanned by systemd-udevd (390) Sep 9 01:42:40.918080 kernel: libata version 3.00 loaded. Sep 9 01:42:40.918094 kernel: ahci 0000:00:1f.2: version 3.0 Sep 9 01:42:40.918194 kernel: PCI Interrupt Link [GSIA] enabled at IRQ 16 Sep 9 01:42:40.918201 kernel: ahci 0000:00:1f.2: AHCI 0001.0000 32 slots 6 ports 1.5 Gbps 0x3f impl SATA mode Sep 9 01:42:40.918251 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only Sep 9 01:42:40.918298 kernel: scsi host0: ahci Sep 9 01:42:40.918366 kernel: scsi host1: ahci Sep 9 01:42:40.918417 kernel: scsi host2: ahci Sep 9 01:42:40.918468 kernel: scsi host3: ahci Sep 9 01:42:40.918516 kernel: scsi host4: ahci Sep 9 01:42:40.918584 kernel: scsi host5: ahci Sep 9 01:42:40.918638 kernel: ata1: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4100 irq 34 Sep 9 01:42:40.918645 kernel: ata2: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4180 irq 34 Sep 9 01:42:40.918651 kernel: ata3: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4200 irq 34 Sep 9 01:42:40.918657 kernel: ata4: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4280 irq 34 Sep 9 01:42:40.918663 kernel: ata5: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4300 irq 34 Sep 9 01:42:40.918671 kernel: ata6: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4380 irq 34 Sep 9 01:42:40.765995 systemd[1]: Started Open-iSCSI. Sep 9 01:42:40.768328 systemd[1]: Starting dracut initqueue hook... Sep 9 01:42:40.773945 systemd-networkd[344]: eth0: Link UP Sep 9 01:42:40.783680 systemd[1]: Found device /dev/disk/by-label/EFI-SYSTEM. Sep 9 01:42:40.796967 systemd[1]: Found device /dev/disk/by-label/ROOT. Sep 9 01:42:40.910443 systemd[1]: Found device /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Sep 9 01:42:40.912997 systemd[1]: Found device /dev/disk/by-partlabel/USR-A. Sep 9 01:42:40.919806 systemd[1]: Found device /dev/disk/by-label/OEM. Sep 9 01:42:40.922118 systemd[1]: Reached target Initrd Root Device. Sep 9 01:42:40.926239 systemd[1]: Starting Generate new UUID for disk GPT if necessary... Sep 9 01:42:40.948787 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 9 01:42:40.954789 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 9 01:42:41.127546 kernel: ata4: SATA link down (SStatus 0 SControl 300) Sep 9 01:42:41.127618 kernel: ata5: SATA link down (SStatus 0 SControl 300) Sep 9 01:42:41.129359 kernel: ata1: SATA link down (SStatus 0 SControl 300) Sep 9 01:42:41.129774 kernel: ata2: SATA link down (SStatus 0 SControl 300) Sep 9 01:42:41.131779 kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Sep 9 01:42:41.133777 kernel: ata6: SATA link down (SStatus 0 SControl 300) Sep 9 01:42:41.135791 kernel: ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 Sep 9 01:42:41.137855 kernel: ata3.00: applying bridge limits Sep 9 01:42:41.139152 kernel: ata3.00: configured for UDMA/100 Sep 9 01:42:41.139784 kernel: scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 Sep 9 01:42:41.172431 kernel: sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray Sep 9 01:42:41.172701 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Sep 9 01:42:41.207783 kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0 Sep 9 01:42:41.616465 systemd[1]: Finished dracut initqueue hook. Sep 9 01:42:41.617000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-initqueue comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:41.617892 systemd[1]: Reached target Preparation for Remote File Systems. Sep 9 01:42:41.620702 systemd[1]: Reached target Remote Encrypted Volumes. Sep 9 01:42:41.622092 systemd[1]: Reached target Remote File Systems. Sep 9 01:42:41.625668 systemd[1]: Starting dracut pre-mount hook... Sep 9 01:42:41.632902 systemd[1]: Finished dracut pre-mount hook. Sep 9 01:42:41.634000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-mount comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:41.759829 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Sep 9 01:42:41.759972 systemd-networkd[344]: eth0: Gained carrier Sep 9 01:42:41.765920 systemd-networkd[344]: eth0: DHCPv4 address 10.0.0.5/16 via 10.0.0.1 Sep 9 01:42:41.953664 disk-uuid[470]: The operation has completed successfully. Sep 9 01:42:41.955571 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 9 01:42:41.980697 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 9 01:42:41.980866 systemd[1]: Finished Generate new UUID for disk GPT if necessary. Sep 9 01:42:41.983000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=disk-uuid comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:41.983000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=disk-uuid comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:41.985436 systemd[1]: Starting Ignition (setup)... Sep 9 01:42:41.998818 kernel: BTRFS info (device vda6): disk space caching is enabled Sep 9 01:42:41.998871 kernel: BTRFS info (device vda6): has skinny extents Sep 9 01:42:42.006845 systemd[1]: mnt-oem.mount: Deactivated successfully. Sep 9 01:42:42.012902 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 9 01:42:42.014334 systemd[1]: Finished Ignition (setup). Sep 9 01:42:42.015000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-setup comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.015000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-setup comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.017304 systemd[1]: Starting Ignition (disks)... Sep 9 01:42:42.022276 ignition[515]: Ignition v0.36.1-15-gde4e6cc9 Sep 9 01:42:42.022312 ignition[515]: Stage: disks Sep 9 01:42:42.022322 ignition[515]: reading system config file "/usr/lib/ignition/base.ign" Sep 9 01:42:42.022342 ignition[515]: no config at "/usr/lib/ignition/base.ign" Sep 9 01:42:42.022386 ignition[515]: parsed url from cmdline: "" Sep 9 01:42:42.022389 ignition[515]: no config URL provided Sep 9 01:42:42.022394 ignition[515]: reading system config file "/usr/lib/ignition/user.ign" Sep 9 01:42:42.022402 ignition[515]: no config at "/usr/lib/ignition/user.ign" Sep 9 01:42:42.022422 ignition[515]: op(1): [started] loading QEMU firmware config module Sep 9 01:42:42.022427 ignition[515]: op(1): executing: "modprobe" "qemu_fw_cfg" Sep 9 01:42:42.023708 ignition[515]: op(1): [finished] loading QEMU firmware config module Sep 9 01:42:42.056557 ignition[515]: parsing config with SHA512: 6f9e8f20ba684202e2d24fcbff13cbdf581f286c853c1d9d18be1cb8904652527705c0eedb1f583429c065409f970dd992d6b43bc4d4cc1f0e3a93a04ed8bb8a Sep 9 01:42:42.058647 ignition[515]: disks: disks passed Sep 9 01:42:42.058770 ignition[515]: Ignition finished successfully Sep 9 01:42:42.061417 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 9 01:42:42.061635 systemd[1]: Finished Ignition (disks). Sep 9 01:42:42.062000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-disks comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.062000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-disks comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.063293 systemd[1]: Reached target Preparation for Local File Systems. Sep 9 01:42:42.067673 systemd[1]: Starting File System Check on /dev/disk/by-label/ROOT... Sep 9 01:42:42.071497 systemd[1]: Starting Verity Setup for /dev/mapper/usr... Sep 9 01:42:42.078191 systemd-fsck[527]: ROOT: clean, 556/553792 files, 37783/553472 blocks Sep 9 01:42:42.081429 systemd[1]: Finished File System Check on /dev/disk/by-label/ROOT. Sep 9 01:42:42.083000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-root comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.084862 systemd[1]: Mounting /sysroot... Sep 9 01:42:42.094805 kernel: device-mapper: verity: sha256 using implementation "sha256-generic" Sep 9 01:42:42.121046 systemd[1]: Found device /dev/mapper/usr. Sep 9 01:42:42.123813 systemd[1]: Finished Verity Setup for /dev/mapper/usr. Sep 9 01:42:42.125000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=verity-setup comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.127357 systemd[1]: Starting File System Check on /dev/mapper/usr... Sep 9 01:42:42.145842 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null) Sep 9 01:42:42.145877 kernel: ext4 filesystem being mounted at /sysroot supports timestamps until 2038 (0x7fffffff) Sep 9 01:42:42.146350 systemd[1]: Mounted /sysroot. Sep 9 01:42:42.146471 systemd[1]: Reached target Initrd Root File System. Sep 9 01:42:42.152433 systemd-fsck[545]: fsck.ext4: Operation not permitted while trying to open /dev/mapper/usr Sep 9 01:42:42.152433 systemd-fsck[545]: You must have r/w access to the filesystem or be root Sep 9 01:42:42.157863 systemd-fsck[541]: fsck failed with exit status 8. Sep 9 01:42:42.157881 systemd-fsck[541]: Ignoring error. Sep 9 01:42:42.160000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-usr comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.158504 systemd[1]: Finished File System Check on /dev/mapper/usr. Sep 9 01:42:42.162536 systemd[1]: Mounting /sysusr/usr... Sep 9 01:42:42.181780 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: (null) Sep 9 01:42:42.181955 systemd[1]: Mounted /sysusr/usr. Sep 9 01:42:42.182087 systemd[1]: Reached target Local File Systems. Sep 9 01:42:42.185119 systemd[1]: Reached target System Initialization. Sep 9 01:42:42.185416 systemd[1]: Reached target Basic System. Sep 9 01:42:42.190800 systemd[1]: Mounting /sysroot/usr... Sep 9 01:42:42.195011 systemd[1]: Mounted /sysroot/usr. Sep 9 01:42:42.197108 systemd[1]: Starting Root filesystem setup... Sep 9 01:42:42.232420 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 9 01:42:42.232601 systemd[1]: Finished Root filesystem setup. Sep 9 01:42:42.233000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.233000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.235247 systemd[1]: Starting Ignition (files)... Sep 9 01:42:42.238299 systemd[1]: Starting /sysroot/boot... Sep 9 01:42:42.240462 ignition[558]: Ignition v0.36.1-15-gde4e6cc9 Sep 9 01:42:42.240477 ignition[558]: Stage: files Sep 9 01:42:42.240511 ignition[558]: reading system config file "/usr/lib/ignition/base.ign" Sep 9 01:42:42.240536 ignition[558]: no config at "/usr/lib/ignition/base.ign" Sep 9 01:42:42.241236 ignition[558]: files: compiled without relabeling support, skipping Sep 9 01:42:42.258979 systemd[1]: Finished /sysroot/boot. Sep 9 01:42:42.258000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=sysroot-boot comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.274488 ignition[558]: files: createUsers: op(1): [started] creating or modifying user "core" Sep 9 01:42:42.274507 ignition[558]: files: createUsers: op(1): executing: "/usr/sbin/usermod" "--root" "/sysroot" "core" Sep 9 01:42:42.275777 ignition[558]: files: createUsers: op(1): [finished] creating or modifying user "core" Sep 9 01:42:42.275783 ignition[558]: files: createUsers: op(2): [started] adding ssh keys to user "core" Sep 9 01:42:42.279000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.279000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.277928 systemd[1]: ignition-files.service: Deactivated successfully. Sep 9 01:42:42.276869 ignition[558]: files: createUsers: op(2): [finished] adding ssh keys to user "core" Sep 9 01:42:42.288000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.288000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.278030 systemd[1]: Finished Ignition (files). Sep 9 01:42:42.276889 ignition[558]: files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/etc/flatcar/update.conf" Sep 9 01:42:42.279911 systemd[1]: Condition check resulted in Populate torcx store to satisfy profile being skipped. Sep 9 01:42:42.293000 audit: BPF prog-id=3 op=UNLOAD Sep 9 01:42:42.277109 ignition[558]: files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/etc/flatcar/update.conf" Sep 9 01:42:42.280493 systemd[1]: Starting Ignition (record completion)... Sep 9 01:42:42.277115 ignition[558]: files: op(4): [started] processing unit "coreos-metadata.service" Sep 9 01:42:42.283290 systemd[1]: Starting Reload Configuration from the Real Root... Sep 9 01:42:42.277137 ignition[558]: files: op(4): op(5): [started] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Sep 9 01:42:42.285819 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 9 01:42:42.277394 ignition[558]: files: op(4): op(5): [finished] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Sep 9 01:42:42.285909 systemd[1]: Finished Ignition (record completion). Sep 9 01:42:42.277398 ignition[558]: files: op(4): [finished] processing unit "coreos-metadata.service" Sep 9 01:42:42.290206 systemd[1]: Reloading. Sep 9 01:42:42.277402 ignition[558]: files: files passed Sep 9 01:42:42.277405 ignition[558]: Ignition finished successfully Sep 9 01:42:42.316000 audit: BPF prog-id=10 op=UNLOAD Sep 9 01:42:42.316000 audit: BPF prog-id=9 op=UNLOAD Sep 9 01:42:42.317000 audit: BPF prog-id=6 op=UNLOAD Sep 9 01:42:42.384000 audit: BPF prog-id=11 op=LOAD Sep 9 01:42:42.385000 audit: BPF prog-id=12 op=LOAD Sep 9 01:42:42.385000 audit: BPF prog-id=13 op=LOAD Sep 9 01:42:42.385000 audit: BPF prog-id=4 op=UNLOAD Sep 9 01:42:42.385000 audit: BPF prog-id=5 op=UNLOAD Sep 9 01:42:42.385000 audit: BPF prog-id=14 op=LOAD Sep 9 01:42:42.386000 audit: BPF prog-id=15 op=LOAD Sep 9 01:42:42.386000 audit: BPF prog-id=16 op=LOAD Sep 9 01:42:42.386000 audit: BPF prog-id=17 op=LOAD Sep 9 01:42:42.386000 audit: BPF prog-id=18 op=LOAD Sep 9 01:42:42.386000 audit: BPF prog-id=7 op=UNLOAD Sep 9 01:42:42.386000 audit: BPF prog-id=8 op=UNLOAD Sep 9 01:42:42.401242 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 9 01:42:42.402794 systemd[1]: Finished Reload Configuration from the Real Root. Sep 9 01:42:42.405000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.405000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.405934 systemd[1]: Reached target Initrd File Systems. Sep 9 01:42:42.408555 systemd[1]: Reached target Initrd Default Target. Sep 9 01:42:42.411162 systemd[1]: Condition check resulted in dracut mount hook being skipped. Sep 9 01:42:42.413356 systemd[1]: Starting dracut pre-pivot and cleanup hook... Sep 9 01:42:42.423201 systemd[1]: Finished dracut pre-pivot and cleanup hook. Sep 9 01:42:42.424000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-pivot comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.425163 systemd[1]: Starting Cleaning Up and Shutting Down Daemons... Sep 9 01:42:42.431444 systemd[1]: Stopped target Host and Network Name Lookups. Sep 9 01:42:42.433232 systemd[1]: Stopped target Remote Encrypted Volumes. Sep 9 01:42:42.435878 systemd[1]: Stopped target Timer Units. Sep 9 01:42:42.438308 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 9 01:42:42.440000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-pivot comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.438397 systemd[1]: Stopped dracut pre-pivot and cleanup hook. Sep 9 01:42:42.440887 systemd[1]: Stopped target Initrd Default Target. Sep 9 01:42:42.443524 systemd[1]: Stopped target Basic System. Sep 9 01:42:42.446093 systemd[1]: Stopped target Initrd Root Device. Sep 9 01:42:42.448717 systemd[1]: Stopped target Path Units. Sep 9 01:42:42.450020 systemd[1]: Stopped target Remote File Systems. Sep 9 01:42:42.452285 systemd[1]: Stopped target Preparation for Remote File Systems. Sep 9 01:42:42.453430 systemd[1]: Stopped target Slice Units. Sep 9 01:42:42.455713 systemd[1]: Stopped target Socket Units. Sep 9 01:42:42.457784 systemd[1]: Stopped target System Initialization. Sep 9 01:42:42.460164 systemd[1]: Stopped target Local File Systems. Sep 9 01:42:42.462229 systemd[1]: Stopped target Preparation for Local File Systems. Sep 9 01:42:42.466797 systemd[1]: Stopped target Swaps. Sep 9 01:42:42.470000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-mount comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.467938 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 9 01:42:42.468031 systemd[1]: Stopped dracut pre-mount hook. Sep 9 01:42:42.470314 systemd[1]: Stopped target Local Encrypted Volumes. Sep 9 01:42:42.473341 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 9 01:42:42.477794 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Sep 9 01:42:42.478391 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 9 01:42:42.481000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-initqueue comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.485216 iscsid[400]: iscsid shutting down. Sep 9 01:42:42.478467 systemd[1]: Stopped dracut initqueue hook. Sep 9 01:42:42.482444 systemd[1]: Stopping Open-iSCSI... Sep 9 01:42:42.487471 systemd[1]: Stopping /sysroot/boot... Sep 9 01:42:42.490000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.490272 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 9 01:42:42.495000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-trigger comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.490419 systemd[1]: Stopped Coldplug All udev Devices. Sep 9 01:42:42.498000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsid comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.491509 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 9 01:42:42.491637 systemd[1]: Stopped dracut pre-trigger hook. Sep 9 01:42:42.497474 systemd[1]: iscsid.service: Deactivated successfully. Sep 9 01:42:42.505000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsiuio comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.497682 systemd[1]: Stopped Open-iSCSI. Sep 9 01:42:42.508000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-cleanup comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.508000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-cleanup comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.500090 systemd[1]: iscsid.socket: Deactivated successfully. Sep 9 01:42:42.500141 systemd[1]: Closed Open-iSCSI iscsid Socket. Sep 9 01:42:42.502363 systemd[1]: Stopping iSCSI UserSpace I/O driver... Sep 9 01:42:42.505038 systemd[1]: iscsiuio.service: Deactivated successfully. Sep 9 01:42:42.505158 systemd[1]: Stopped iSCSI UserSpace I/O driver. Sep 9 01:42:42.517000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.506095 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 9 01:42:42.506189 systemd[1]: Finished Cleaning Up and Shutting Down Daemons. Sep 9 01:42:42.510268 systemd[1]: Stopped target Network. Sep 9 01:42:42.511513 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 9 01:42:42.525000 audit: BPF prog-id=14 op=UNLOAD Sep 9 01:42:42.511592 systemd[1]: Closed Open-iSCSI iscsiuio Socket. Sep 9 01:42:42.512820 systemd[1]: Stopping Network Name Resolution... Sep 9 01:42:42.517628 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 9 01:42:42.517722 systemd[1]: Stopped Network Name Resolution. Sep 9 01:42:42.520881 systemd[1]: Stopping Network Configuration... Sep 9 01:42:42.522919 systemd-networkd[344]: eth0: DHCP lease lost Sep 9 01:42:42.533000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-networkd comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.530813 systemd-networkd[344]: eth0: DHCPv6 lease lost Sep 9 01:42:42.531948 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 9 01:42:42.532045 systemd[1]: Stopped Network Configuration. Sep 9 01:42:42.540000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.534179 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 9 01:42:42.534215 systemd[1]: Closed Network Service Netlink Socket. Sep 9 01:42:42.542000 audit: BPF prog-id=15 op=UNLOAD Sep 9 01:42:42.536940 systemd[1]: Stopping Network Cleanup... Sep 9 01:42:42.537528 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 9 01:42:42.537565 systemd[1]: Stopped Apply Kernel Variables. Sep 9 01:42:42.540984 systemd[1]: Stopping Rule-based Manager for Device Events and Files... Sep 9 01:42:42.554000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.549218 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 9 01:42:42.549331 systemd[1]: Stopped Rule-based Manager for Device Events and Files. Sep 9 01:42:42.558551 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 9 01:42:42.559000 audit: BPF prog-id=16 op=UNLOAD Sep 9 01:42:42.560204 systemd[1]: Stopped Network Cleanup. Sep 9 01:42:42.561000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=network-cleanup comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.562491 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 9 01:42:42.563897 systemd[1]: Stopped /sysroot/boot. Sep 9 01:42:42.565000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=sysroot-boot comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.566130 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 9 01:42:42.566175 systemd[1]: Closed udev Control Socket. Sep 9 01:42:42.570282 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 9 01:42:42.570328 systemd[1]: Closed udev Kernel Socket. Sep 9 01:42:42.575075 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 9 01:42:42.575143 systemd[1]: Stopped dracut pre-udev hook. Sep 9 01:42:42.578000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.579979 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 9 01:42:42.580014 systemd[1]: Stopped dracut cmdline hook. Sep 9 01:42:42.583000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.583661 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 9 01:42:42.584000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.583693 systemd[1]: Stopped dracut ask for additional cmdline parameters. Sep 9 01:42:42.588961 systemd[1]: Starting Cleanup udev Database... Sep 9 01:42:42.592331 systemd[1]: Stopping Hardware RNG Entropy Gatherer Daemon... Sep 9 01:42:42.595964 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 9 01:42:42.597640 systemd[1]: Stopped Create Static Device Nodes in /dev. Sep 9 01:42:42.600000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.600428 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 9 01:42:42.600457 systemd[1]: Stopped Create List of Static Device Nodes. Sep 9 01:42:42.603000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.604714 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 9 01:42:42.606286 systemd[1]: Stopped Setup Virtual Console. Sep 9 01:42:42.608000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.608874 systemd[1]: rngd.service: Deactivated successfully. Sep 9 01:42:42.610218 systemd[1]: Stopped Hardware RNG Entropy Gatherer Daemon. Sep 9 01:42:42.613000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=rngd comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.613222 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 9 01:42:42.614882 systemd[1]: Finished Cleanup udev Database. Sep 9 01:42:42.617000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-udevadm-cleanup-db comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.617000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-udevadm-cleanup-db comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:42.617393 systemd[1]: Reached target Switch Root. Sep 9 01:42:42.620354 systemd[1]: Starting Switch Root... Sep 9 01:42:42.627554 systemd[1]: Switching root. Sep 9 01:42:42.631000 audit: BPF prog-id=11 op=UNLOAD Sep 9 01:42:42.644622 systemd-journald[190]: Journal stopped Sep 9 01:42:46.296662 systemd-journald[190]: Received SIGTERM from PID 1 (systemd). Sep 9 01:42:46.296734 kernel: SELinux: policy capability network_peer_controls=1 Sep 9 01:42:46.296867 kernel: SELinux: policy capability open_perms=1 Sep 9 01:42:46.296898 kernel: SELinux: policy capability extended_socket_class=1 Sep 9 01:42:46.296910 kernel: SELinux: policy capability always_check_network=0 Sep 9 01:42:46.296921 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 9 01:42:46.296933 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 9 01:42:46.296945 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 9 01:42:46.297005 systemd[1]: Successfully loaded SELinux policy in 41.789ms. Sep 9 01:42:46.297026 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 5.208ms. Sep 9 01:42:46.297040 systemd[1]: systemd 249 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 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Sep 9 01:42:46.297052 systemd[1]: Detected virtualization kvm. Sep 9 01:42:46.297065 systemd[1]: Detected architecture x86-64. Sep 9 01:42:46.297077 systemd[1]: Detected first boot. Sep 9 01:42:46.297088 systemd[1]: Initializing machine ID from VM UUID. Sep 9 01:42:46.297099 systemd[1]: Populated /etc with preset unit settings. Sep 9 01:42:46.297113 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. Sep 9 01:42:46.297128 systemd[1]: initrd-switch-root.service: Current command vanished from the unit file, execution of the command list won't be resumed. Sep 9 01:42:46.297140 kernel: kauditd_printk_skb: 84 callbacks suppressed Sep 9 01:42:46.297150 kernel: audit: type=1334 audit(1788918166.032:95): prog-id=21 op=LOAD Sep 9 01:42:46.297164 kernel: audit: type=1334 audit(1788918166.036:96): prog-id=22 op=LOAD Sep 9 01:42:46.297174 kernel: audit: type=1334 audit(1788918166.037:97): prog-id=23 op=LOAD Sep 9 01:42:46.297186 kernel: audit: type=1334 audit(1788918166.037:98): prog-id=12 op=UNLOAD Sep 9 01:42:46.297197 kernel: audit: type=1334 audit(1788918166.037:99): prog-id=13 op=UNLOAD Sep 9 01:42:46.297207 kernel: audit: type=1334 audit(1788918166.051:100): prog-id=24 op=LOAD Sep 9 01:42:46.297218 kernel: audit: type=1334 audit(1788918166.051:101): prog-id=21 op=UNLOAD Sep 9 01:42:46.297232 kernel: audit: type=1334 audit(1788918166.054:102): prog-id=25 op=LOAD Sep 9 01:42:46.297243 kernel: audit: type=1334 audit(1788918166.056:103): prog-id=26 op=LOAD Sep 9 01:42:46.297254 kernel: audit: type=1334 audit(1788918166.056:104): prog-id=22 op=UNLOAD Sep 9 01:42:46.297265 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 9 01:42:46.297274 systemd[1]: Stopped Switch Root. Sep 9 01:42:46.297282 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 9 01:42:46.297290 systemd[1]: Created slice Slice /system/addon-config. Sep 9 01:42:46.297303 systemd[1]: Created slice Slice /system/addon-run. Sep 9 01:42:46.297316 systemd[1]: Created slice Slice /system/getty. Sep 9 01:42:46.297327 systemd[1]: Created slice Slice /system/modprobe. Sep 9 01:42:46.297338 systemd[1]: Created slice Slice /system/serial-getty. Sep 9 01:42:46.297350 systemd[1]: Created slice Slice /system/system-cloudinit. Sep 9 01:42:46.297361 systemd[1]: Created slice Slice /system/systemd-fsck. Sep 9 01:42:46.297372 systemd[1]: Created slice User and Session Slice. Sep 9 01:42:46.297382 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Sep 9 01:42:46.297396 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Sep 9 01:42:46.297407 systemd[1]: Set up automount Boot partition Automount Point. Sep 9 01:42:46.297420 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Sep 9 01:42:46.297430 systemd[1]: Stopped target Switch Root. Sep 9 01:42:46.297440 systemd[1]: Stopped target Initrd File Systems. Sep 9 01:42:46.297451 systemd[1]: Stopped target Initrd Root File System. Sep 9 01:42:46.297463 systemd[1]: Reached target Remote Encrypted Volumes. Sep 9 01:42:46.297474 systemd[1]: Reached target Remote File Systems. Sep 9 01:42:46.297485 systemd[1]: Reached target Slice Units. Sep 9 01:42:46.297496 systemd[1]: Reached target Swaps. Sep 9 01:42:46.297507 systemd[1]: Reached target Verify torcx succeeded. Sep 9 01:42:46.297519 systemd[1]: Reached target Local Verity Protected Volumes. Sep 9 01:42:46.297533 systemd[1]: Listening on Process Core Dump Socket. Sep 9 01:42:46.297545 systemd[1]: Listening on initctl Compatibility Named Pipe. Sep 9 01:42:46.297556 systemd[1]: Listening on Network Service Netlink Socket. Sep 9 01:42:46.297567 systemd[1]: Listening on udev Control Socket. Sep 9 01:42:46.297579 systemd[1]: Listening on udev Kernel Socket. Sep 9 01:42:46.297590 systemd[1]: Mounting Huge Pages File System... Sep 9 01:42:46.297672 systemd[1]: Mounting POSIX Message Queue File System... Sep 9 01:42:46.297684 systemd[1]: Mounting External Media Directory... Sep 9 01:42:46.297694 systemd[1]: Condition check resulted in /proc/xen being skipped. Sep 9 01:42:46.297703 systemd[1]: Mounting Kernel Debug File System... Sep 9 01:42:46.297711 systemd[1]: Mounting Kernel Trace File System... Sep 9 01:42:46.297718 systemd[1]: Mounting Temporary Directory /tmp... Sep 9 01:42:46.297731 systemd[1]: Starting Create missing system files... Sep 9 01:42:46.297742 systemd[1]: Starting Create List of Static Device Nodes... Sep 9 01:42:46.297798 systemd[1]: Starting Load Kernel Module configfs... Sep 9 01:42:46.297809 systemd[1]: Starting Load Kernel Module drm... Sep 9 01:42:46.297821 systemd[1]: Starting Load Kernel Module fuse... Sep 9 01:42:46.297832 systemd[1]: Condition check resulted in Create /etc/nsswitch.conf being skipped. Sep 9 01:42:46.297843 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 9 01:42:46.297859 systemd[1]: Stopped File System Check on Root Device. Sep 9 01:42:46.297869 kernel: fuse: init (API version 7.32) Sep 9 01:42:46.297881 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 9 01:42:46.297893 systemd[1]: Stopped systemd-fsck-usr.service. Sep 9 01:42:46.297904 systemd[1]: Stopped Journal Service. Sep 9 01:42:46.297916 systemd[1]: Starting Journal Service... Sep 9 01:42:46.297928 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Sep 9 01:42:46.297939 systemd[1]: Starting Remount Root and Kernel File Systems... Sep 9 01:42:46.297951 systemd[1]: Starting Apply Kernel Variables... Sep 9 01:42:46.297966 systemd[1]: Starting Coldplug All udev Devices... Sep 9 01:42:46.297981 systemd-journald[778]: Journal started Sep 9 01:42:46.298026 systemd-journald[778]: Runtime Journal (/run/log/journal/ad9198a0e79144b7bd07e4563d4ce0b7) is 6.0M, max 48.7M, 42.6M free. Sep 9 01:42:42.761000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 9 01:42:42.810000 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 Sep 9 01:42:42.810000 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 Sep 9 01:42:42.810000 audit: BPF prog-id=19 op=LOAD Sep 9 01:42:42.810000 audit: BPF prog-id=19 op=UNLOAD Sep 9 01:42:42.810000 audit: BPF prog-id=20 op=LOAD Sep 9 01:42:42.810000 audit: BPF prog-id=20 op=UNLOAD Sep 9 01:42:42.876000 audit[724]: AVC avc: denied { associate } for pid=724 comm="torcx-generator" name="docker" dev="tmpfs" ino=2 scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Sep 9 01:42:46.032000 audit: BPF prog-id=21 op=LOAD Sep 9 01:42:46.036000 audit: BPF prog-id=22 op=LOAD Sep 9 01:42:46.037000 audit: BPF prog-id=23 op=LOAD Sep 9 01:42:46.037000 audit: BPF prog-id=12 op=UNLOAD Sep 9 01:42:46.037000 audit: BPF prog-id=13 op=UNLOAD Sep 9 01:42:46.051000 audit: BPF prog-id=24 op=LOAD Sep 9 01:42:46.051000 audit: BPF prog-id=21 op=UNLOAD Sep 9 01:42:46.054000 audit: BPF prog-id=25 op=LOAD Sep 9 01:42:46.056000 audit: BPF prog-id=26 op=LOAD Sep 9 01:42:46.056000 audit: BPF prog-id=22 op=UNLOAD Sep 9 01:42:46.056000 audit: BPF prog-id=23 op=UNLOAD Sep 9 01:42:46.061000 audit: BPF prog-id=27 op=LOAD Sep 9 01:42:46.061000 audit: BPF prog-id=24 op=UNLOAD Sep 9 01:42:46.061000 audit: BPF prog-id=28 op=LOAD Sep 9 01:42:46.061000 audit: BPF prog-id=29 op=LOAD Sep 9 01:42:46.061000 audit: BPF prog-id=25 op=UNLOAD Sep 9 01:42:46.061000 audit: BPF prog-id=26 op=UNLOAD Sep 9 01:42:46.062000 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/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:46.067000 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/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:46.068000 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/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:46.071000 audit: BPF prog-id=27 op=UNLOAD Sep 9 01:42:46.251000 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/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:46.258000 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/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:46.261000 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/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:46.261000 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/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:46.272000 audit: BPF prog-id=30 op=LOAD Sep 9 01:42:46.273000 audit: BPF prog-id=31 op=LOAD Sep 9 01:42:46.273000 audit: BPF prog-id=32 op=LOAD Sep 9 01:42:46.273000 audit: BPF prog-id=28 op=UNLOAD Sep 9 01:42:46.273000 audit: BPF prog-id=29 op=UNLOAD Sep 9 01:42:46.294000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Sep 9 01:42:42.874798 /usr/lib64/systemd/system-generators/torcx-generator[724]: time="2026-09-09T01:42:42Z" 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/3033.2.4 /usr/share/oem/torcx/store /var/lib/torcx/store/3033.2.4 /var/lib/torcx/store]" Sep 9 01:42:46.032506 systemd[1]: Queued start job for default target Multi-User System. Sep 9 01:42:42.875176 /usr/lib64/systemd/system-generators/torcx-generator[724]: time="2026-09-09T01:42:42Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Sep 9 01:42:46.062552 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 9 01:42:42.875189 /usr/lib64/systemd/system-generators/torcx-generator[724]: time="2026-09-09T01:42:42Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Sep 9 01:42:42.875348 /usr/lib64/systemd/system-generators/torcx-generator[724]: time="2026-09-09T01:42:42Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Sep 9 01:42:42.875356 /usr/lib64/systemd/system-generators/torcx-generator[724]: time="2026-09-09T01:42:42Z" level=debug msg="skipped missing lower profile" missing profile=oem Sep 9 01:42:42.875378 /usr/lib64/systemd/system-generators/torcx-generator[724]: time="2026-09-09T01:42:42Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Sep 9 01:42:42.875388 /usr/lib64/systemd/system-generators/torcx-generator[724]: time="2026-09-09T01:42:42Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Sep 9 01:42:42.875532 /usr/lib64/systemd/system-generators/torcx-generator[724]: time="2026-09-09T01:42:42Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Sep 9 01:42:42.875554 /usr/lib64/systemd/system-generators/torcx-generator[724]: time="2026-09-09T01:42:42Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Sep 9 01:42:42.875568 /usr/lib64/systemd/system-generators/torcx-generator[724]: time="2026-09-09T01:42:42Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Sep 9 01:42:42.876252 /usr/lib64/systemd/system-generators/torcx-generator[724]: time="2026-09-09T01:42:42Z" 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 Sep 9 01:42:42.876277 /usr/lib64/systemd/system-generators/torcx-generator[724]: time="2026-09-09T01:42:42Z" 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 Sep 9 01:42:42.876295 /usr/lib64/systemd/system-generators/torcx-generator[724]: time="2026-09-09T01:42:42Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3033.2.4: no such file or directory" path=/usr/share/oem/torcx/store/3033.2.4 Sep 9 01:42:42.876306 /usr/lib64/systemd/system-generators/torcx-generator[724]: time="2026-09-09T01:42:42Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Sep 9 01:42:42.876319 /usr/lib64/systemd/system-generators/torcx-generator[724]: time="2026-09-09T01:42:42Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3033.2.4: no such file or directory" path=/var/lib/torcx/store/3033.2.4 Sep 9 01:42:42.876328 /usr/lib64/systemd/system-generators/torcx-generator[724]: time="2026-09-09T01:42:42Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Sep 9 01:42:45.752796 /usr/lib64/systemd/system-generators/torcx-generator[724]: time="2026-09-09T01:42:45Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Sep 9 01:42:45.753091 /usr/lib64/systemd/system-generators/torcx-generator[724]: time="2026-09-09T01:42:45Z" 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 Sep 9 01:42:45.753198 /usr/lib64/systemd/system-generators/torcx-generator[724]: time="2026-09-09T01:42:45Z" 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 Sep 9 01:42:45.753322 /usr/lib64/systemd/system-generators/torcx-generator[724]: time="2026-09-09T01:42:45Z" 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]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Sep 9 01:42:45.753361 /usr/lib64/systemd/system-generators/torcx-generator[724]: time="2026-09-09T01:42:45Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Sep 9 01:42:45.753424 /usr/lib64/systemd/system-generators/torcx-generator[724]: time="2026-09-09T01:42:45Z" 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 Sep 9 01:42:46.303308 systemd[1]: verity-setup.service: Deactivated successfully. Sep 9 01:42:46.303369 systemd[1]: Stopped verity-setup.service. Sep 9 01:42:46.304000 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/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:46.309869 systemd[1]: Condition check resulted in Set fake PV driver version for XenServer being skipped. Sep 9 01:42:46.316321 systemd[1]: Started Journal Service. Sep 9 01:42:46.316000 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/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:46.317314 systemd[1]: Mounted Huge Pages File System. Sep 9 01:42:46.319129 systemd[1]: Mounted POSIX Message Queue File System. Sep 9 01:42:46.321310 systemd[1]: Mounted External Media Directory. Sep 9 01:42:46.323642 systemd[1]: Mounted Kernel Debug File System. Sep 9 01:42:46.325952 systemd[1]: Mounted Kernel Trace File System. Sep 9 01:42:46.327977 systemd[1]: Mounted Temporary Directory /tmp. Sep 9 01:42:46.330319 systemd[1]: Finished Create missing system files. Sep 9 01:42:46.331000 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/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:46.332242 systemd[1]: Finished Create List of Static Device Nodes. Sep 9 01:42:46.333000 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/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:46.333918 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 9 01:42:46.334160 systemd[1]: Finished Load Kernel Module configfs. Sep 9 01:42:46.336000 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/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:46.336000 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/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:46.336419 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 9 01:42:46.336829 systemd[1]: Finished Load Kernel Module drm. Sep 9 01:42:46.338000 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/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:46.338000 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/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:46.338942 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 9 01:42:46.339169 systemd[1]: Finished Load Kernel Module fuse. Sep 9 01:42:46.340000 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/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:46.340000 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/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:46.340888 systemd[1]: Finished Remount Root and Kernel File Systems. Sep 9 01:42:46.342000 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/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:46.350391 systemd[1]: Finished Apply Kernel Variables. Sep 9 01:42:46.351000 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/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:46.354227 systemd[1]: Mounting FUSE Control File System... Sep 9 01:42:46.356529 systemd[1]: Mounting Kernel Configuration File System... Sep 9 01:42:46.358478 systemd[1]: Condition check resulted in Remount Root File System being skipped. Sep 9 01:42:46.359954 systemd[1]: Starting Rebuild Hardware Database... Sep 9 01:42:46.362731 systemd[1]: Starting Flush Journal to Persistent Storage... Sep 9 01:42:46.364920 systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. Sep 9 01:42:46.366011 systemd[1]: Starting Load/Save Random Seed... Sep 9 01:42:46.368391 systemd[1]: Starting Create System Users... Sep 9 01:42:46.373125 systemd-journald[778]: Time spent on flushing to /var/log/journal/ad9198a0e79144b7bd07e4563d4ce0b7 is 17.223ms for 999 entries. Sep 9 01:42:46.373125 systemd-journald[778]: System Journal (/var/log/journal/ad9198a0e79144b7bd07e4563d4ce0b7) is 8.0M, max 203.0M, 195.0M free. Sep 9 01:42:46.382000 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/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:46.384000 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/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:46.373807 systemd[1]: Mounted FUSE Control File System. Sep 9 01:42:46.378908 systemd[1]: Mounted Kernel Configuration File System. Sep 9 01:42:46.381502 systemd[1]: Finished Load/Save Random Seed. Sep 9 01:42:46.383872 systemd[1]: Finished Coldplug All udev Devices. Sep 9 01:42:46.385795 systemd[1]: Reached target First Boot Complete. Sep 9 01:42:46.391991 systemd[1]: Starting Wait for udev To Complete Device Initialization... Sep 9 01:42:46.401877 systemd-sysusers[789]: Creating group sgx with gid 999. Sep 9 01:42:46.402971 systemd-sysusers[789]: Creating group systemd-oom with gid 998. Sep 9 01:42:46.404052 systemd-sysusers[789]: Creating user systemd-oom (systemd Userspace OOM Killer) with uid 998 and gid 998. Sep 9 01:42:46.405111 systemd-sysusers[789]: Creating group systemd-timesync with gid 997. Sep 9 01:42:46.406065 systemd-sysusers[789]: Creating user systemd-timesync (systemd Time Synchronization) with uid 997 and gid 997. Sep 9 01:42:46.407282 systemd-sysusers[789]: Creating group systemd-coredump with gid 996. Sep 9 01:42:46.407855 systemd[1]: Finished Flush Journal to Persistent Storage. Sep 9 01:42:46.410000 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/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:46.411040 systemd-sysusers[789]: Creating user systemd-coredump (systemd Core Dumper) with uid 996 and gid 996. Sep 9 01:42:46.482469 systemd[1]: Finished Create System Users. Sep 9 01:42:46.483000 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/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:46.486396 systemd[1]: Starting Create Static Device Nodes in /dev... Sep 9 01:42:46.507582 systemd[1]: Finished Create Static Device Nodes in /dev. Sep 9 01:42:46.508000 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/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:46.936183 systemd[1]: Finished Rebuild Hardware Database. Sep 9 01:42:46.937000 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/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:46.946000 audit: BPF prog-id=33 op=LOAD Sep 9 01:42:46.946000 audit: BPF prog-id=34 op=LOAD Sep 9 01:42:46.947000 audit: BPF prog-id=35 op=LOAD Sep 9 01:42:46.947000 audit: BPF prog-id=17 op=UNLOAD Sep 9 01:42:46.947000 audit: BPF prog-id=18 op=UNLOAD Sep 9 01:42:46.948497 systemd[1]: Starting Rule-based Manager for Device Events and Files... Sep 9 01:42:46.990250 systemd[1]: Started Rule-based Manager for Device Events and Files. Sep 9 01:42:46.992000 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/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:46.999000 audit: BPF prog-id=36 op=LOAD Sep 9 01:42:47.001088 systemd[1]: Starting Network Configuration... Sep 9 01:42:47.018838 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2 Sep 9 01:42:47.025796 kernel: ACPI: Power Button [PWRF] Sep 9 01:42:47.025375 systemd-udevd[805]: Using default interface naming scheme 'v249'. Sep 9 01:42:47.072000 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/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:47.069266 systemd-networkd[806]: lo: Link UP Sep 9 01:42:47.069274 systemd-networkd[806]: lo: Gained carrier Sep 9 01:42:47.070056 systemd-networkd[806]: Enumeration completed Sep 9 01:42:47.070352 systemd[1]: Started Network Configuration. Sep 9 01:42:47.076301 systemd-networkd[806]: eth0: Link UP Sep 9 01:42:47.093211 systemd-networkd[806]: eth0: Gained carrier Sep 9 01:42:47.080000 audit[799]: AVC avc: denied { confidentiality } for pid=799 comm="systemd-udevd" lockdown_reason="use of tracefs" scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=lockdown permissive=1 Sep 9 01:42:47.107136 systemd-networkd[806]: eth0: DHCPv4 address 10.0.0.5/16 via 10.0.0.1 Sep 9 01:42:47.136654 udevadm[791]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Sep 9 01:42:47.147949 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt Sep 9 01:42:47.148197 kernel: i2c i2c-0: 1/1 memory slots populated (from DMI) Sep 9 01:42:47.148333 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD Sep 9 01:42:47.157805 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 Sep 9 01:42:47.176806 kernel: mousedev: PS/2 mouse device common for all mice Sep 9 01:42:47.298123 systemd[1]: Finished Wait for udev To Complete Device Initialization. Sep 9 01:42:47.299000 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/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:47.310215 systemd[1]: Starting Activation of LVM2 logical volumes... Sep 9 01:42:47.334664 lvm[825]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 9 01:42:47.358319 systemd[1]: Finished Activation of LVM2 logical volumes. Sep 9 01:42:47.359000 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/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:47.360218 systemd[1]: Reached target Local Encrypted Volumes. Sep 9 01:42:47.376188 systemd[1]: Starting Activation of LVM2 logical volumes... Sep 9 01:42:47.385191 lvm[826]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 9 01:42:47.419135 systemd[1]: Finished Activation of LVM2 logical volumes. Sep 9 01:42:47.420000 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/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:47.421588 systemd[1]: Reached target Preparation for Local File Systems. Sep 9 01:42:47.424052 systemd[1]: Condition check resulted in Virtual Machine and Container Storage (Compatibility) being skipped. Sep 9 01:42:47.424237 systemd[1]: Reached target Containers. Sep 9 01:42:47.437235 systemd[1]: Starting File System Check on /dev/disk/by-label/OEM... Sep 9 01:42:47.454219 systemd[1]: Finished File System Check on /dev/disk/by-label/OEM. Sep 9 01:42:47.459000 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/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:47.469832 systemd[1]: Mounting /usr/share/oem... Sep 9 01:42:47.528828 kernel: BTRFS info (device vda6): disk space caching is enabled Sep 9 01:42:47.529120 kernel: BTRFS info (device vda6): has skinny extents Sep 9 01:42:47.534999 systemd[1]: Mounted /usr/share/oem. Sep 9 01:42:47.536664 systemd[1]: Reached target Local File Systems. Sep 9 01:42:47.549980 systemd[1]: Starting Rebuild Dynamic Linker Cache... Sep 9 01:42:47.554913 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Sep 9 01:42:47.555093 systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Sep 9 01:42:47.558619 systemd[1]: Starting Commit a transient machine-id on disk... Sep 9 01:42:47.563409 systemd[1]: Starting Create Volatile Files and Directories... Sep 9 01:42:47.577415 systemd-tmpfiles[851]: /usr/lib64/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Sep 9 01:42:47.579213 systemd-tmpfiles[851]: /usr/lib64/tmpfiles.d/systemd.conf:33: Duplicate line for path "/var/lib/systemd", ignoring. Sep 9 01:42:47.581292 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 9 01:42:47.581923 systemd[1]: Finished Commit a transient machine-id on disk. Sep 9 01:42:47.584000 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/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:47.609321 systemd-tmpfiles[851]: Detected autofs mount point /boot during canonicalization of /boot. Sep 9 01:42:47.609353 systemd-tmpfiles[851]: Skipping /boot Sep 9 01:42:47.615401 systemd-tmpfiles[851]: Detected autofs mount point /boot during canonicalization of /boot. Sep 9 01:42:47.615429 systemd-tmpfiles[851]: Skipping /boot Sep 9 01:42:47.658669 systemd[1]: Finished Create Volatile Files and Directories. Sep 9 01:42:47.660000 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/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:47.666930 systemd[1]: Starting Load Security Auditing Rules... Sep 9 01:42:47.669454 systemd[1]: Starting Clean up broken links in /etc/ssl/certs... Sep 9 01:42:47.672245 systemd[1]: Starting Rebuild Journal Catalog... Sep 9 01:42:47.673000 audit: BPF prog-id=37 op=LOAD Sep 9 01:42:47.675498 systemd[1]: Starting Network Name Resolution... Sep 9 01:42:47.676000 audit: BPF prog-id=38 op=LOAD Sep 9 01:42:47.678677 systemd[1]: Starting Network Time Synchronization... Sep 9 01:42:47.681330 systemd[1]: Starting Record System Boot/Shutdown in UTMP... Sep 9 01:42:47.685000 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/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:47.684080 systemd[1]: Finished Clean up broken links in /etc/ssl/certs. Sep 9 01:42:47.686588 systemd[1]: Condition check resulted in Update CA bundle at /etc/ssl/certs/ca-certificates.crt being skipped. Sep 9 01:42:47.693513 systemd[1]: Finished Rebuild Journal Catalog. Sep 9 01:42:47.694000 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/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:47.699000 audit[858]: SYSTEM_BOOT pid=858 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg=' comm="systemd-update-utmp" exe="/usr/lib64/systemd/systemd-update-utmp" hostname=? addr=? terminal=? res=success' Sep 9 01:42:47.710000 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/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:42:47.709088 systemd[1]: Finished Record System Boot/Shutdown in UTMP. Sep 9 01:42:47.723906 augenrules[872]: No rules Sep 9 01:42:47.723000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Sep 9 01:42:47.725303 systemd[1]: Finished Load Security Auditing Rules. Sep 9 01:42:47.728318 systemd-resolved[856]: Positive Trust Anchors: Sep 9 01:42:47.728335 systemd-resolved[856]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 9 01:42:47.728361 systemd-resolved[856]: 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 Sep 9 01:42:47.729056 systemd-resolved[856]: Defaulting to hostname 'linux'. Sep 9 01:42:47.730649 systemd[1]: Started Network Name Resolution. Sep 9 01:42:49.084365 systemd-timesyncd[857]: Initial synchronization to time server 10.0.0.1:123 (10.0.0.1). Sep 9 01:42:49.084418 systemd-resolved[856]: Clock change detected. Flushing caches. Sep 9 01:42:49.087487 systemd[1]: Started Network Time Synchronization. Sep 9 01:42:49.089432 systemd[1]: Reached target Network. Sep 9 01:42:49.090791 systemd[1]: Reached target Host and Network Name Lookups. Sep 9 01:42:49.092504 systemd[1]: Reached target System Time Set. Sep 9 01:42:49.275054 systemd[1]: Finished Rebuild Dynamic Linker Cache. Sep 9 01:42:49.287627 systemd[1]: Starting Update is Completed... Sep 9 01:42:49.294016 systemd[1]: Finished Update is Completed. Sep 9 01:42:49.295768 systemd[1]: Reached target System Initialization. Sep 9 01:42:49.297361 systemd[1]: Started Watch for update engine configuration changes. Sep 9 01:42:49.299122 systemd[1]: Started Watch for a cloud-config at /var/lib/flatcar-install/user_data. Sep 9 01:42:49.300981 systemd[1]: Started Daily Log Rotation. Sep 9 01:42:49.302396 systemd[1]: Started Weekly check for MD array's redundancy information.. Sep 9 01:42:49.304503 systemd[1]: Started Daily Cleanup of Temporary Directories. Sep 9 01:42:49.306055 systemd[1]: Condition check resulted in Update Engine Stub Timer being skipped. Sep 9 01:42:49.306130 systemd[1]: Reached target Path Units. Sep 9 01:42:49.307448 systemd[1]: Reached target Timer Units. Sep 9 01:42:49.309191 systemd[1]: Listening on D-Bus System Message Bus Socket. Sep 9 01:42:49.311920 systemd[1]: Starting Docker Socket for the API... Sep 9 01:42:49.316213 systemd[1]: Listening on OpenSSH Server Socket. Sep 9 01:42:49.317997 systemd[1]: Listening on Docker Socket for the API. Sep 9 01:42:49.319481 systemd[1]: Reached target Socket Units. Sep 9 01:42:49.320907 systemd[1]: Reached target Basic System. Sep 9 01:42:49.322289 systemd[1]: Condition check resulted in Configure Addon /usr/share/oem being skipped. Sep 9 01:42:49.322314 systemd[1]: Condition check resulted in Run Addon /usr/share/oem being skipped. Sep 9 01:42:49.323324 systemd[1]: Started D-Bus System Message Bus. Sep 9 01:42:49.327599 systemd[1]: Starting Extend Filesystems... Sep 9 01:42:49.328949 systemd[1]: Condition check resulted in Modifies /etc/environment for CoreOS being skipped. Sep 9 01:42:49.329830 systemd[1]: Starting Generate /run/flatcar/motd... Sep 9 01:42:49.332561 systemd[1]: Starting Install an ssh key from /proc/cmdline... Sep 9 01:42:49.336918 systemd[1]: Starting Generate sshd host keys... Sep 9 01:42:49.340132 systemd[1]: Condition check resulted in Load cloud-config from /usr/share/oem/cloud-config.yml being skipped. Sep 9 01:42:49.342632 extend-filesystems[882]: Found sr0 Sep 9 01:42:49.342632 extend-filesystems[882]: Found vda Sep 9 01:42:49.342632 extend-filesystems[882]: Found vda1 Sep 9 01:42:49.342632 extend-filesystems[882]: Found vda2 Sep 9 01:42:49.342632 extend-filesystems[882]: Found vda3 Sep 9 01:42:49.342632 extend-filesystems[882]: Found usr Sep 9 01:42:49.342632 extend-filesystems[882]: Found vda4 Sep 9 01:42:49.342632 extend-filesystems[882]: Found vda6 Sep 9 01:42:49.342632 extend-filesystems[882]: Found vda7 Sep 9 01:42:49.342632 extend-filesystems[882]: Found vda9 Sep 9 01:42:49.342632 extend-filesystems[882]: Checking size of /dev/vda9 Sep 9 01:42:49.340255 systemd[1]: Reached target Load system-provided cloud configs. Sep 9 01:42:49.389384 extend-filesystems[882]: Old size kept for /dev/vda9 Sep 9 01:42:49.346552 systemd[1]: Starting User Login Management... Sep 9 01:42:49.348465 systemd[1]: Condition check resulted in TCG Core Services Daemon being skipped. Sep 9 01:42:49.349262 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 9 01:42:49.350168 systemd[1]: Starting Update Engine... Sep 9 01:42:49.351821 systemd[1]: Condition check resulted in Load cloud-config from url defined in /proc/cmdline being skipped. Sep 9 01:42:49.351845 systemd[1]: Reached target Load user-provided cloud configs. Sep 9 01:42:49.354973 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 9 01:42:49.355293 systemd[1]: Finished Extend Filesystems. Sep 9 01:42:49.365520 systemd[1]: motdgen.service: Deactivated successfully. Sep 9 01:42:49.365778 systemd[1]: Finished Generate /run/flatcar/motd. Sep 9 01:42:49.368626 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 9 01:42:49.368834 systemd[1]: Finished Install an ssh key from /proc/cmdline. Sep 9 01:42:49.510694 systemd-logind[896]: Watching system buttons on /dev/input/event1 (Power Button) Sep 9 01:42:49.510753 systemd-logind[896]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Sep 9 01:42:49.511006 systemd-logind[896]: New seat seat0. Sep 9 01:42:49.513639 systemd[1]: Started User Login Management. Sep 9 01:42:49.531892 update_engine[900]: I0909 01:42:49.530486 900 main.cc:89] Flatcar Update Engine starting Sep 9 01:42:49.539646 systemd[1]: Started Update Engine. Sep 9 01:42:49.541092 update_engine[900]: I0909 01:42:49.540980 900 update_check_scheduler.cc:74] Next update check in 8m54s Sep 9 01:42:49.549261 systemd[1]: Started Cluster reboot manager. Sep 9 01:42:49.773561 systemd-networkd[806]: eth0: Gained IPv6LL Sep 9 01:42:49.781405 sshd_keygen[902]: ssh-keygen: generating new host keys: RSA DSA ECDSA ED25519 Sep 9 01:42:49.815820 systemd[1]: Finished Generate sshd host keys. Sep 9 01:42:49.822877 systemd[1]: Starting Generate /run/issue... Sep 9 01:42:49.830596 systemd[1]: issuegen.service: Deactivated successfully. Sep 9 01:42:49.830860 systemd[1]: Finished Generate /run/issue. Sep 9 01:42:49.835635 locksmithd[905]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 9 01:42:49.842127 systemd[1]: Starting Permit User Sessions... Sep 9 01:42:49.853640 systemd[1]: Finished Permit User Sessions. Sep 9 01:42:49.870994 systemd[1]: Started Getty on tty1. Sep 9 01:42:49.878044 systemd[1]: Started Serial Getty on ttyS0. Sep 9 01:42:49.880934 systemd[1]: Reached target Login Prompts. Sep 9 01:42:49.882742 systemd[1]: Reached target Multi-User System. Sep 9 01:42:49.889934 systemd[1]: Starting Record Runlevel Change in UTMP... Sep 9 01:42:49.904679 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Sep 9 01:42:49.905589 systemd[1]: Finished Record Runlevel Change in UTMP. Sep 9 01:42:49.908758 systemd[1]: Startup finished in 1.010s (kernel) + 3.136s (initrd) + 5.847s (userspace) = 9.994s. Sep 9 01:42:59.142583 systemd[1]: Created slice Slice /system/sshd. Sep 9 01:42:59.143841 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:42942). Sep 9 01:42:59.193919 sshd[926]: Accepted publickey for core from 10.0.0.1 port 42942 ssh2: RSA SHA256:eIa+CObse2AU5Y2cEkuMbKUHpwlgF9CI4voF+MQMG+M Sep 9 01:42:59.196791 sshd[926]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Sep 9 01:42:59.238527 systemd[1]: Created slice User Slice of UID 500. Sep 9 01:42:59.248782 systemd[1]: Starting User Runtime Directory /run/user/500... Sep 9 01:42:59.250775 systemd-logind[896]: New session 1 of user core. Sep 9 01:42:59.257690 systemd[1]: Finished User Runtime Directory /run/user/500. Sep 9 01:42:59.259697 systemd[1]: Starting User Manager for UID 500... Sep 9 01:42:59.263398 systemd[929]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 9 01:42:59.317555 kernel: hrtimer: interrupt took 4191101 ns Sep 9 01:42:59.327676 systemd[929]: Queued start job for default target Main User Target. Sep 9 01:42:59.327835 systemd[929]: Reached target Paths. Sep 9 01:42:59.327848 systemd[929]: Reached target Sockets. Sep 9 01:42:59.327858 systemd[929]: Reached target Timers. Sep 9 01:42:59.327867 systemd[929]: Reached target Basic System. Sep 9 01:42:59.327917 systemd[929]: Reached target Main User Target. Sep 9 01:42:59.327927 systemd[929]: Startup finished in 58ms. Sep 9 01:42:59.328108 systemd[1]: Started User Manager for UID 500. Sep 9 01:42:59.333499 systemd[1]: Started Session 1 of User core. Sep 9 01:42:59.400887 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:42946). Sep 9 01:42:59.438518 sshd[938]: Accepted publickey for core from 10.0.0.1 port 42946 ssh2: RSA SHA256:eIa+CObse2AU5Y2cEkuMbKUHpwlgF9CI4voF+MQMG+M Sep 9 01:42:59.440523 sshd[938]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Sep 9 01:42:59.445997 systemd-logind[896]: New session 2 of user core. Sep 9 01:42:59.459502 systemd[1]: Started Session 2 of User core. Sep 9 01:42:59.545057 sshd[938]: pam_unix(sshd:session): session closed for user core Sep 9 01:42:59.553053 systemd[1]: sshd@1-10.0.0.5:22-10.0.0.1:42946.service: Deactivated successfully. Sep 9 01:42:59.553660 systemd[1]: session-2.scope: Deactivated successfully. Sep 9 01:42:59.554880 systemd-logind[896]: Session 2 logged out. Waiting for processes to exit. Sep 9 01:42:59.556158 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:42962). Sep 9 01:42:59.556901 systemd-logind[896]: Removed session 2. Sep 9 01:42:59.590896 sshd[944]: Accepted publickey for core from 10.0.0.1 port 42962 ssh2: RSA SHA256:eIa+CObse2AU5Y2cEkuMbKUHpwlgF9CI4voF+MQMG+M Sep 9 01:42:59.592943 sshd[944]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Sep 9 01:42:59.597691 systemd-logind[896]: New session 3 of user core. Sep 9 01:42:59.601527 systemd[1]: Started Session 3 of User core. Sep 9 01:42:59.660551 sshd[944]: pam_unix(sshd:session): session closed for user core Sep 9 01:42:59.668595 systemd[1]: sshd@2-10.0.0.5:22-10.0.0.1:42962.service: Deactivated successfully. Sep 9 01:42:59.670319 systemd[1]: session-3.scope: Deactivated successfully. Sep 9 01:42:59.671075 systemd-logind[896]: Session 3 logged out. Waiting for processes to exit. Sep 9 01:42:59.672820 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:42964). Sep 9 01:42:59.674310 systemd-logind[896]: Removed session 3. Sep 9 01:42:59.711853 sshd[950]: Accepted publickey for core from 10.0.0.1 port 42964 ssh2: RSA SHA256:eIa+CObse2AU5Y2cEkuMbKUHpwlgF9CI4voF+MQMG+M Sep 9 01:42:59.713793 sshd[950]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Sep 9 01:42:59.718997 systemd-logind[896]: New session 4 of user core. Sep 9 01:42:59.724470 systemd[1]: Started Session 4 of User core. Sep 9 01:42:59.787948 sshd[950]: pam_unix(sshd:session): session closed for user core Sep 9 01:42:59.799932 systemd[1]: sshd@3-10.0.0.5:22-10.0.0.1:42964.service: Deactivated successfully. Sep 9 01:42:59.800799 systemd[1]: session-4.scope: Deactivated successfully. Sep 9 01:42:59.801472 systemd-logind[896]: Session 4 logged out. Waiting for processes to exit. Sep 9 01:42:59.802613 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:42980). Sep 9 01:42:59.803595 systemd-logind[896]: Removed session 4. Sep 9 01:42:59.885971 sshd[956]: Accepted publickey for core from 10.0.0.1 port 42980 ssh2: RSA SHA256:eIa+CObse2AU5Y2cEkuMbKUHpwlgF9CI4voF+MQMG+M Sep 9 01:42:59.888215 sshd[956]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Sep 9 01:42:59.896054 systemd-logind[896]: New session 5 of user core. Sep 9 01:42:59.904766 systemd[1]: Started Session 5 of User core. Sep 9 01:42:59.976122 sudo[959]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/setenforce 1 Sep 9 01:42:59.976420 sudo[959]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Sep 9 01:42:59.992737 sudo[959]: pam_unix(sudo:session): session closed for user root Sep 9 01:43:00.001332 sshd[956]: pam_unix(sshd:session): session closed for user core Sep 9 01:43:00.039655 dbus-daemon[881]: [system] Reloaded configuration Sep 9 01:43:00.046644 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:42998). Sep 9 01:43:00.047213 systemd[1]: sshd@4-10.0.0.5:22-10.0.0.1:42980.service: Deactivated successfully. Sep 9 01:43:00.048026 systemd[1]: session-5.scope: Deactivated successfully. Sep 9 01:43:00.048827 systemd-logind[896]: Session 5 logged out. Waiting for processes to exit. Sep 9 01:43:00.049890 systemd-logind[896]: Removed session 5. Sep 9 01:43:00.085591 sshd[962]: Accepted publickey for core from 10.0.0.1 port 42998 ssh2: RSA SHA256:eIa+CObse2AU5Y2cEkuMbKUHpwlgF9CI4voF+MQMG+M Sep 9 01:43:00.087299 sshd[962]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Sep 9 01:43:00.091176 systemd-logind[896]: New session 6 of user core. Sep 9 01:43:00.096441 systemd[1]: Started Session 6 of User core. Sep 9 01:43:00.155679 sudo[967]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Sep 9 01:43:00.155970 sudo[967]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Sep 9 01:43:00.159314 sudo[967]: pam_unix(sudo:session): session closed for user root Sep 9 01:43:00.164030 sudo[966]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl restart audit-rules Sep 9 01:43:00.164295 sudo[966]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Sep 9 01:43:00.178997 systemd[1]: Stopping Load Security Auditing Rules... Sep 9 01:43:00.179000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Sep 9 01:43:00.180581 auditctl[970]: No rules Sep 9 01:43:00.180749 systemd[1]: audit-rules.service: Deactivated successfully. Sep 9 01:43:00.180966 systemd[1]: Stopped Load Security Auditing Rules. Sep 9 01:43:00.182656 kernel: kauditd_printk_skb: 61 callbacks suppressed Sep 9 01:43:00.182715 kernel: audit: type=1305 audit(1788918180.179:166): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Sep 9 01:43:00.182556 systemd[1]: Starting Load Security Auditing Rules... Sep 9 01:43:00.179000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=audit-rules comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:43:00.197288 kernel: audit: type=1131 audit(1788918180.179:167): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=audit-rules comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:43:00.209766 augenrules[987]: No rules Sep 9 01:43:00.210765 systemd[1]: Finished Load Security Auditing Rules. Sep 9 01:43:00.209000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=audit-rules comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:43:00.212043 sudo[966]: pam_unix(sudo:session): session closed for user root Sep 9 01:43:00.214504 sshd[962]: pam_unix(sshd:session): session closed for user core Sep 9 01:43:00.210000 audit[966]: USER_END pid=966 uid=500 auid=500 ses=6 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Sep 9 01:43:00.230777 kernel: audit: type=1130 audit(1788918180.209:168): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=audit-rules comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:43:00.230928 kernel: audit: type=1106 audit(1788918180.210:169): pid=966 uid=500 auid=500 ses=6 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Sep 9 01:43:00.230949 kernel: audit: type=1104 audit(1788918180.210:170): pid=966 uid=500 auid=500 ses=6 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Sep 9 01:43:00.210000 audit[966]: CRED_DISP pid=966 uid=500 auid=500 ses=6 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Sep 9 01:43:00.214000 audit[962]: USER_END pid=962 uid=0 auid=500 ses=6 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:00.215000 audit[962]: CRED_DISP pid=962 uid=0 auid=500 ses=6 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:00.260858 kernel: audit: type=1106 audit(1788918180.214:171): pid=962 uid=0 auid=500 ses=6 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:00.261064 kernel: audit: type=1104 audit(1788918180.215:172): pid=962 uid=0 auid=500 ses=6 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:00.267464 systemd[1]: sshd@5-10.0.0.5:22-10.0.0.1:42998.service: Deactivated successfully. Sep 9 01:43:00.266000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@5-10.0.0.5:22-10.0.0.1:42998 comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:43:00.268821 systemd[1]: session-6.scope: Deactivated successfully. Sep 9 01:43:00.269758 systemd-logind[896]: Session 6 logged out. Waiting for processes to exit. Sep 9 01:43:00.271014 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:43030). Sep 9 01:43:00.272761 systemd-logind[896]: Removed session 6. Sep 9 01:43:00.269000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@6-10.0.0.5:22-10.0.0.1:43030 comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:43:00.287539 kernel: audit: type=1131 audit(1788918180.266:173): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@5-10.0.0.5:22-10.0.0.1:42998 comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:43:00.287714 kernel: audit: type=1130 audit(1788918180.269:174): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@6-10.0.0.5:22-10.0.0.1:43030 comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:43:00.356000 audit[993]: USER_ACCT pid=993 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:accounting grantors=pam_access,pam_unix,pam_faillock,pam_permit acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:00.358525 sshd[993]: Accepted publickey for core from 10.0.0.1 port 43030 ssh2: RSA SHA256:eIa+CObse2AU5Y2cEkuMbKUHpwlgF9CI4voF+MQMG+M Sep 9 01:43:00.367000 audit[993]: CRED_ACQ pid=993 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:00.369044 sshd[993]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Sep 9 01:43:00.369328 kernel: audit: type=1101 audit(1788918180.356:175): pid=993 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:accounting grantors=pam_access,pam_unix,pam_faillock,pam_permit acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:00.373308 systemd-logind[896]: New session 7 of user core. Sep 9 01:43:00.381659 systemd[1]: Started Session 7 of User core. Sep 9 01:43:00.388000 audit[993]: USER_START pid=993 uid=0 auid=500 ses=7 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_open grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:00.389000 audit[995]: CRED_ACQ pid=995 uid=0 auid=500 ses=7 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:00.440000 audit[996]: USER_ACCT pid=996 uid=500 auid=500 ses=7 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:accounting grantors=pam_unix,pam_faillock,pam_permit acct="core" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Sep 9 01:43:00.441338 sudo[996]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/mkdir -p . Sep 9 01:43:00.440000 audit[996]: CRED_REFR pid=996 uid=500 auid=500 ses=7 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Sep 9 01:43:00.441617 sudo[996]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Sep 9 01:43:00.442000 audit[996]: USER_START pid=996 uid=500 auid=500 ses=7 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_open grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Sep 9 01:43:00.445740 sudo[996]: pam_unix(sudo:session): session closed for user root Sep 9 01:43:00.444000 audit[996]: USER_END pid=996 uid=500 auid=500 ses=7 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Sep 9 01:43:00.444000 audit[996]: CRED_DISP pid=996 uid=500 auid=500 ses=7 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Sep 9 01:43:00.447992 sshd[993]: pam_unix(sshd:session): session closed for user core Sep 9 01:43:00.448000 audit[993]: USER_END pid=993 uid=0 auid=500 ses=7 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:00.448000 audit[993]: CRED_DISP pid=993 uid=0 auid=500 ses=7 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:00.456032 systemd[1]: sshd@6-10.0.0.5:22-10.0.0.1:43030.service: Deactivated successfully. Sep 9 01:43:00.455000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@6-10.0.0.5:22-10.0.0.1:43030 comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:43:00.457484 systemd[1]: session-7.scope: Deactivated successfully. Sep 9 01:43:00.458188 systemd-logind[896]: Session 7 logged out. Waiting for processes to exit. Sep 9 01:43:00.459451 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:43062). Sep 9 01:43:00.458000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@7-10.0.0.5:22-10.0.0.1:43062 comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:43:00.460157 systemd-logind[896]: Removed session 7. Sep 9 01:43:00.497000 audit[1000]: USER_ACCT pid=1000 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:accounting grantors=pam_access,pam_unix,pam_faillock,pam_permit acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:00.498346 sshd[1000]: Accepted publickey for core from 10.0.0.1 port 43062 ssh2: RSA SHA256:eIa+CObse2AU5Y2cEkuMbKUHpwlgF9CI4voF+MQMG+M Sep 9 01:43:00.498000 audit[1000]: CRED_ACQ pid=1000 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:00.500639 sshd[1000]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Sep 9 01:43:00.508115 systemd-logind[896]: New session 8 of user core. Sep 9 01:43:00.515641 systemd[1]: Started Session 8 of User core. Sep 9 01:43:00.525000 audit[1000]: USER_START pid=1000 uid=0 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_open grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:00.527000 audit[1002]: CRED_ACQ pid=1002 uid=0 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:00.587000 audit[1003]: USER_ACCT pid=1003 uid=500 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:accounting grantors=pam_unix,pam_faillock,pam_permit acct="core" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Sep 9 01:43:00.589415 sudo[1003]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/install -m 0755 /dev/stdin kolet Sep 9 01:43:00.588000 audit[1003]: CRED_REFR pid=1003 uid=500 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Sep 9 01:43:00.589786 sudo[1003]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Sep 9 01:43:00.592000 audit[1003]: USER_START pid=1003 uid=500 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_open grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Sep 9 01:43:00.870000 audit[1003]: USER_END pid=1003 uid=500 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Sep 9 01:43:00.870000 audit[1003]: CRED_DISP pid=1003 uid=500 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Sep 9 01:43:00.871349 sudo[1003]: pam_unix(sudo:session): session closed for user root Sep 9 01:43:00.873393 sshd[1000]: pam_unix(sshd:session): session closed for user core Sep 9 01:43:00.873000 audit[1000]: USER_END pid=1000 uid=0 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:00.874000 audit[1000]: CRED_DISP pid=1000 uid=0 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:00.886873 systemd[1]: sshd@7-10.0.0.5:22-10.0.0.1:43062.service: Deactivated successfully. Sep 9 01:43:00.885000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@7-10.0.0.5:22-10.0.0.1:43062 comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:43:00.887733 systemd[1]: session-8.scope: Deactivated successfully. Sep 9 01:43:00.888870 systemd-logind[896]: Session 8 logged out. Waiting for processes to exit. Sep 9 01:43:00.890649 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:43126). Sep 9 01:43:00.889000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@8-10.0.0.5:22-10.0.0.1:43126 comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:43:00.892569 systemd-logind[896]: Removed session 8. Sep 9 01:43:00.939000 audit[1007]: USER_ACCT pid=1007 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:accounting grantors=pam_access,pam_unix,pam_faillock,pam_permit acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:00.940926 sshd[1007]: Accepted publickey for core from 10.0.0.1 port 43126 ssh2: RSA SHA256:eIa+CObse2AU5Y2cEkuMbKUHpwlgF9CI4voF+MQMG+M Sep 9 01:43:00.940000 audit[1007]: CRED_ACQ pid=1007 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:00.942117 sshd[1007]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Sep 9 01:43:00.945563 systemd-logind[896]: New session 9 of user core. Sep 9 01:43:00.949594 systemd[1]: Started Session 9 of User core. Sep 9 01:43:00.953000 audit[1007]: USER_START pid=1007 uid=0 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_open grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:00.955000 audit[1009]: CRED_ACQ pid=1009 uid=0 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:01.005000 audit[1010]: USER_ACCT pid=1010 uid=500 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:accounting grantors=pam_unix,pam_faillock,pam_permit acct="core" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Sep 9 01:43:01.006000 audit[1010]: CRED_REFR pid=1010 uid=500 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Sep 9 01:43:01.007294 sudo[1010]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/mkdir -p /updates Sep 9 01:43:01.007568 sudo[1010]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Sep 9 01:43:01.008000 audit[1010]: USER_START pid=1010 uid=500 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_open grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Sep 9 01:43:01.011794 sudo[1010]: pam_unix(sudo:session): session closed for user root Sep 9 01:43:01.010000 audit[1010]: USER_END pid=1010 uid=500 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Sep 9 01:43:01.010000 audit[1010]: CRED_DISP pid=1010 uid=500 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Sep 9 01:43:01.013406 sshd[1007]: pam_unix(sshd:session): session closed for user core Sep 9 01:43:01.013000 audit[1007]: USER_END pid=1007 uid=0 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:01.013000 audit[1007]: CRED_DISP pid=1007 uid=0 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:01.020212 systemd[1]: sshd@8-10.0.0.5:22-10.0.0.1:43126.service: Deactivated successfully. Sep 9 01:43:01.019000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@8-10.0.0.5:22-10.0.0.1:43126 comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:43:01.020945 systemd[1]: session-9.scope: Deactivated successfully. Sep 9 01:43:01.021613 systemd-logind[896]: Session 9 logged out. Waiting for processes to exit. Sep 9 01:43:01.022843 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:43136). Sep 9 01:43:01.021000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@9-10.0.0.5:22-10.0.0.1:43136 comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:43:01.023593 systemd-logind[896]: Removed session 9. Sep 9 01:43:01.055000 audit[1014]: USER_ACCT pid=1014 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:accounting grantors=pam_access,pam_unix,pam_faillock,pam_permit acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:01.057248 sshd[1014]: Accepted publickey for core from 10.0.0.1 port 43136 ssh2: RSA SHA256:eIa+CObse2AU5Y2cEkuMbKUHpwlgF9CI4voF+MQMG+M Sep 9 01:43:01.056000 audit[1014]: CRED_ACQ pid=1014 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:01.058386 sshd[1014]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Sep 9 01:43:01.061115 systemd-logind[896]: New session 10 of user core. Sep 9 01:43:01.068416 systemd[1]: Started Session 10 of User core. Sep 9 01:43:01.072000 audit[1014]: USER_START pid=1014 uid=0 auid=500 ses=10 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_open grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:01.074000 audit[1016]: CRED_ACQ pid=1016 uid=0 auid=500 ses=10 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:01.122000 audit[1017]: USER_ACCT pid=1017 uid=500 auid=500 ses=10 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:accounting grantors=pam_unix,pam_faillock,pam_permit acct="core" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Sep 9 01:43:01.122000 audit[1017]: CRED_REFR pid=1017 uid=500 auid=500 ses=10 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Sep 9 01:43:01.123770 sudo[1017]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/install -m 0755 /dev/stdin /updates/update.gz Sep 9 01:43:01.123941 sudo[1017]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Sep 9 01:43:01.124000 audit[1017]: USER_START pid=1017 uid=500 auid=500 ses=10 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_open grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Sep 9 01:43:03.537754 sudo[1017]: pam_unix(sudo:session): session closed for user root Sep 9 01:43:03.536000 audit[1017]: USER_END pid=1017 uid=500 auid=500 ses=10 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Sep 9 01:43:03.536000 audit[1017]: CRED_DISP pid=1017 uid=500 auid=500 ses=10 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Sep 9 01:43:03.539801 sshd[1014]: pam_unix(sshd:session): session closed for user core Sep 9 01:43:03.539000 audit[1014]: USER_END pid=1014 uid=0 auid=500 ses=10 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:03.540000 audit[1014]: CRED_DISP pid=1014 uid=0 auid=500 ses=10 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:03.545695 systemd[1]: sshd@9-10.0.0.5:22-10.0.0.1:43136.service: Deactivated successfully. Sep 9 01:43:03.544000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@9-10.0.0.5:22-10.0.0.1:43136 comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:43:03.546252 systemd[1]: session-10.scope: Deactivated successfully. Sep 9 01:43:03.546419 systemd[1]: session-10.scope: Consumed 3.143s CPU time. Sep 9 01:43:03.546877 systemd-logind[896]: Session 10 logged out. Waiting for processes to exit. Sep 9 01:43:03.547706 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:43152). Sep 9 01:43:03.546000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@10-10.0.0.5:22-10.0.0.1:43152 comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:43:03.548392 systemd-logind[896]: Removed session 10. Sep 9 01:43:03.657000 audit[1021]: USER_ACCT pid=1021 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:accounting grantors=pam_access,pam_unix,pam_faillock,pam_permit acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:03.658803 sshd[1021]: Accepted publickey for core from 10.0.0.1 port 43152 ssh2: RSA SHA256:eIa+CObse2AU5Y2cEkuMbKUHpwlgF9CI4voF+MQMG+M Sep 9 01:43:03.658000 audit[1021]: CRED_ACQ pid=1021 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:03.660056 sshd[1021]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Sep 9 01:43:03.663663 systemd-logind[896]: New session 11 of user core. Sep 9 01:43:03.668413 systemd[1]: Started Session 11 of User core. Sep 9 01:43:03.672000 audit[1021]: USER_START pid=1021 uid=0 auid=500 ses=11 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_open grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:03.674000 audit[1023]: CRED_ACQ pid=1023 uid=0 auid=500 ses=11 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:03.738000 audit[1024]: USER_ACCT pid=1024 uid=500 auid=500 ses=11 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:accounting grantors=pam_unix,pam_faillock,pam_permit acct="core" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Sep 9 01:43:03.738000 audit[1024]: CRED_REFR pid=1024 uid=500 auid=500 ses=11 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Sep 9 01:43:03.739539 sudo[1024]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemd-run --quiet ./kolet run cl.update.docker-btrfs-compat Omaha Sep 9 01:43:03.739695 sudo[1024]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Sep 9 01:43:03.740000 audit[1024]: USER_START pid=1024 uid=500 auid=500 ses=11 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_open grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Sep 9 01:43:03.755511 systemd[1]: Started /home/core/./kolet run cl.update.docker-btrfs-compat Omaha. Sep 9 01:43:03.754000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=run-rbcab94bd17044d57bb5c191e62ddcc19 comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:43:03.756316 sudo[1024]: pam_unix(sudo:session): session closed for user root Sep 9 01:43:03.755000 audit[1024]: USER_END pid=1024 uid=500 auid=500 ses=11 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Sep 9 01:43:03.755000 audit[1024]: CRED_DISP pid=1024 uid=500 auid=500 ses=11 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Sep 9 01:43:03.758312 sshd[1021]: pam_unix(sshd:session): session closed for user core Sep 9 01:43:03.757000 audit[1021]: USER_END pid=1021 uid=0 auid=500 ses=11 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:03.757000 audit[1021]: CRED_DISP pid=1021 uid=0 auid=500 ses=11 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:03.757000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@11-10.0.0.5:22-10.0.0.1:43164 comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:43:03.761447 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:43164). Sep 9 01:43:03.762859 systemd[1]: sshd@10-10.0.0.5:22-10.0.0.1:43152.service: Deactivated successfully. Sep 9 01:43:03.757000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@10-10.0.0.5:22-10.0.0.1:43152 comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:43:03.765304 systemd[1]: session-11.scope: Deactivated successfully. Sep 9 01:43:03.766629 systemd-logind[896]: Session 11 logged out. Waiting for processes to exit. Sep 9 01:43:03.767558 systemd-logind[896]: Removed session 11. Sep 9 01:43:03.802000 audit[1032]: USER_ACCT pid=1032 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:accounting grantors=pam_access,pam_unix,pam_faillock,pam_permit acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:03.803818 sshd[1032]: Accepted publickey for core from 10.0.0.1 port 43164 ssh2: RSA SHA256:eIa+CObse2AU5Y2cEkuMbKUHpwlgF9CI4voF+MQMG+M Sep 9 01:43:03.803000 audit[1032]: CRED_ACQ pid=1032 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:03.805417 sshd[1032]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Sep 9 01:43:03.821544 systemd-logind[896]: New session 12 of user core. Sep 9 01:43:03.827441 systemd[1]: Started Session 12 of User core. Sep 9 01:43:03.830000 audit[1032]: USER_START pid=1032 uid=0 auid=500 ses=12 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_open grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:03.831000 audit[1038]: CRED_ACQ pid=1038 uid=0 auid=500 ses=12 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:04.924541 sshd[1032]: pam_unix(sshd:session): session closed for user core Sep 9 01:43:04.924000 audit[1032]: USER_END pid=1032 uid=0 auid=500 ses=12 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:04.924000 audit[1032]: CRED_DISP pid=1032 uid=0 auid=500 ses=12 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.0.0.1 addr=10.0.0.1 terminal=ssh res=success' Sep 9 01:43:04.927569 systemd[1]: sshd@11-10.0.0.5:22-10.0.0.1:43164.service: Deactivated successfully. Sep 9 01:43:04.926000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@11-10.0.0.5:22-10.0.0.1:43164 comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:43:04.928494 systemd[1]: session-12.scope: Deactivated successfully. Sep 9 01:43:04.929117 systemd-logind[896]: Session 12 logged out. Waiting for processes to exit. Sep 9 01:43:04.929814 systemd-logind[896]: Removed session 12. Sep 9 01:43:34.945803 update_engine[900]: I0909 01:43:34.945525 900 update_attempter.cc:505] Updating boot flags... Sep 9 01:51:44.005859 update_engine[900]: I0909 01:51:44.003561 900 prefs.cc:51] certificate-report-to-send-update not present in /var/lib/update_engine/prefs Sep 9 01:51:44.005859 update_engine[900]: I0909 01:51:44.004558 900 prefs.cc:51] certificate-report-to-send-download not present in /var/lib/update_engine/prefs Sep 9 01:51:44.029094 update_engine[900]: I0909 01:51:44.010532 900 prefs.cc:51] aleph-version not present in /var/lib/update_engine/prefs Sep 9 01:51:44.029611 update_engine[900]: I0909 01:51:44.029213 900 omaha_request_params.cc:62] Current group set to stable Sep 9 01:51:44.034920 update_engine[900]: I0909 01:51:44.034406 900 update_attempter.cc:495] Already updated boot flags. Skipping. Sep 9 01:51:44.034920 update_engine[900]: I0909 01:51:44.034528 900 update_attempter.cc:638] Scheduling an action processor start. Sep 9 01:51:44.034920 update_engine[900]: I0909 01:51:44.034660 900 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Sep 9 01:51:44.035584 update_engine[900]: I0909 01:51:44.035151 900 prefs.cc:51] previous-version not present in /var/lib/update_engine/prefs Sep 9 01:51:44.038651 locksmithd[905]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_CHECKING_FOR_UPDATE" NewVersion=0.0.0 NewSize=0 Sep 9 01:51:44.039202 update_engine[900]: I0909 01:51:44.038815 900 omaha_request_action.cc:268] Posting an Omaha request to disabled Sep 9 01:51:44.039202 update_engine[900]: I0909 01:51:44.038915 900 omaha_request_action.cc:269] Request: Sep 9 01:51:44.039202 update_engine[900]: Sep 9 01:51:44.039202 update_engine[900]: Sep 9 01:51:44.039202 update_engine[900]: Sep 9 01:51:44.039202 update_engine[900]: Sep 9 01:51:44.039202 update_engine[900]: Sep 9 01:51:44.039202 update_engine[900]: Sep 9 01:51:44.039202 update_engine[900]: Sep 9 01:51:44.039202 update_engine[900]: Sep 9 01:51:44.039202 update_engine[900]: I0909 01:51:44.039114 900 libcurl_http_fetcher.cc:48] Starting/Resuming transfer Sep 9 01:51:44.043463 update_engine[900]: I0909 01:51:44.043363 900 libcurl_http_fetcher.cc:152] Setting up curl options for HTTP Sep 9 01:51:44.057192 update_engine[900]: I0909 01:51:44.044070 900 libcurl_http_fetcher.cc:435] Setting up timeout source: 1 seconds. Sep 9 01:51:44.164726 update_engine[900]: E0909 01:51:44.164057 900 libcurl_http_fetcher.cc:251] Unable to get http response code: Could not resolve host: disabled Sep 9 01:51:44.166216 update_engine[900]: I0909 01:51:44.165858 900 libcurl_http_fetcher.cc:268] No HTTP response, retry 1 Sep 9 01:51:54.930622 update_engine[900]: I0909 01:51:54.930099 900 libcurl_http_fetcher.cc:48] Starting/Resuming transfer Sep 9 01:51:54.932189 update_engine[900]: I0909 01:51:54.931149 900 libcurl_http_fetcher.cc:152] Setting up curl options for HTTP Sep 9 01:51:54.935633 update_engine[900]: E0909 01:51:54.935458 900 libcurl_http_fetcher.cc:251] Unable to get http response code: Could not resolve host: disabled Sep 9 01:51:54.936189 update_engine[900]: I0909 01:51:54.936041 900 libcurl_http_fetcher.cc:268] No HTTP response, retry 2 Sep 9 01:52:04.946450 update_engine[900]: I0909 01:52:04.946128 900 libcurl_http_fetcher.cc:48] Starting/Resuming transfer Sep 9 01:52:04.950609 update_engine[900]: I0909 01:52:04.946557 900 libcurl_http_fetcher.cc:152] Setting up curl options for HTTP Sep 9 01:52:04.950609 update_engine[900]: I0909 01:52:04.947269 900 libcurl_http_fetcher.cc:435] Setting up timeout source: 1 seconds. Sep 9 01:52:04.952127 update_engine[900]: E0909 01:52:04.951877 900 libcurl_http_fetcher.cc:251] Unable to get http response code: Could not resolve host: disabled Sep 9 01:52:04.952492 update_engine[900]: I0909 01:52:04.952425 900 libcurl_http_fetcher.cc:268] No HTTP response, retry 3 Sep 9 01:52:14.940548 update_engine[900]: I0909 01:52:14.934278 900 libcurl_http_fetcher.cc:48] Starting/Resuming transfer Sep 9 01:52:14.940548 update_engine[900]: I0909 01:52:14.939572 900 libcurl_http_fetcher.cc:152] Setting up curl options for HTTP Sep 9 01:52:14.941389 update_engine[900]: I0909 01:52:14.941329 900 libcurl_http_fetcher.cc:435] Setting up timeout source: 1 seconds. Sep 9 01:52:14.942942 update_engine[900]: E0909 01:52:14.942834 900 libcurl_http_fetcher.cc:251] Unable to get http response code: Could not resolve host: disabled Sep 9 01:52:14.943328 update_engine[900]: I0909 01:52:14.942960 900 libcurl_http_fetcher.cc:282] Transfer resulted in an error (0), 0 bytes downloaded Sep 9 01:52:14.943328 update_engine[900]: I0909 01:52:14.942970 900 omaha_request_action.cc:619] Omaha request response: Sep 9 01:52:14.943328 update_engine[900]: E0909 01:52:14.942973 900 omaha_request_action.cc:633] Omaha request network transfer failed. Sep 9 01:52:14.943515 update_engine[900]: I0909 01:52:14.943383 900 action_processor.cc:68] ActionProcessor::ActionComplete: OmahaRequestAction action failed. Aborting processing. Sep 9 01:52:14.943515 update_engine[900]: I0909 01:52:14.943456 900 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Sep 9 01:52:14.943515 update_engine[900]: I0909 01:52:14.943459 900 update_attempter.cc:302] Processing Done. Sep 9 01:52:14.943835 update_engine[900]: E0909 01:52:14.943714 900 update_attempter.cc:615] Update failed. Sep 9 01:52:14.943835 update_engine[900]: I0909 01:52:14.943825 900 utils.cc:600] Converting error code 2000 to kActionCodeOmahaErrorInHTTPResponse Sep 9 01:52:14.944130 update_engine[900]: I0909 01:52:14.943831 900 payload_state.cc:97] Updating payload state for error code: 37 (kActionCodeOmahaErrorInHTTPResponse) Sep 9 01:52:14.944130 update_engine[900]: I0909 01:52:14.943856 900 payload_state.cc:103] Ignoring failures until we get a valid Omaha response. Sep 9 01:52:14.944130 update_engine[900]: I0909 01:52:14.944051 900 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Sep 9 01:52:14.944130 update_engine[900]: I0909 01:52:14.944078 900 omaha_request_action.cc:268] Posting an Omaha request to disabled Sep 9 01:52:14.944130 update_engine[900]: I0909 01:52:14.944082 900 omaha_request_action.cc:269] Request: Sep 9 01:52:14.944130 update_engine[900]: Sep 9 01:52:14.944130 update_engine[900]: Sep 9 01:52:14.944130 update_engine[900]: Sep 9 01:52:14.944130 update_engine[900]: Sep 9 01:52:14.944130 update_engine[900]: Sep 9 01:52:14.944130 update_engine[900]: Sep 9 01:52:14.944130 update_engine[900]: I0909 01:52:14.944087 900 libcurl_http_fetcher.cc:48] Starting/Resuming transfer Sep 9 01:52:14.944859 update_engine[900]: I0909 01:52:14.944507 900 libcurl_http_fetcher.cc:152] Setting up curl options for HTTP Sep 9 01:52:14.947468 update_engine[900]: I0909 01:52:14.947264 900 libcurl_http_fetcher.cc:435] Setting up timeout source: 1 seconds. Sep 9 01:52:14.952610 locksmithd[905]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_REPORTING_ERROR_EVENT" NewVersion=0.0.0 NewSize=0 Sep 9 01:52:14.962025 update_engine[900]: E0909 01:52:14.953723 900 libcurl_http_fetcher.cc:251] Unable to get http response code: Could not resolve host: disabled Sep 9 01:52:14.964736 update_engine[900]: I0909 01:52:14.964150 900 libcurl_http_fetcher.cc:282] Transfer resulted in an error (0), 0 bytes downloaded Sep 9 01:52:14.964736 update_engine[900]: I0909 01:52:14.964579 900 omaha_request_action.cc:619] Omaha request response: Sep 9 01:52:14.966765 update_engine[900]: I0909 01:52:14.964756 900 action_processor.cc:65] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Sep 9 01:52:14.966765 update_engine[900]: I0909 01:52:14.964763 900 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Sep 9 01:52:14.966765 update_engine[900]: I0909 01:52:14.964771 900 update_attempter.cc:302] Processing Done. Sep 9 01:52:14.966765 update_engine[900]: I0909 01:52:14.965830 900 update_attempter.cc:306] Error event sent. Sep 9 01:52:14.966765 update_engine[900]: I0909 01:52:14.966361 900 update_check_scheduler.cc:74] Next update check in 46m17s Sep 9 01:52:14.984297 locksmithd[905]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_IDLE" NewVersion=0.0.0 NewSize=0 Sep 9 01:57:44.257884 systemd[1]: Starting Cleanup of Temporary Directories... Sep 9 01:57:44.299361 systemd-tmpfiles[1068]: /usr/lib64/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Sep 9 01:57:44.301779 systemd-tmpfiles[1068]: /usr/lib64/tmpfiles.d/systemd.conf:33: Duplicate line for path "/var/lib/systemd", ignoring. Sep 9 01:57:44.366189 systemd-tmpfiles[1068]: Detected autofs mount point /boot during canonicalization of /boot. Sep 9 01:57:44.366374 systemd-tmpfiles[1068]: Skipping /boot Sep 9 01:57:44.406856 systemd[1]: systemd-tmpfiles-clean.service: Deactivated successfully. Sep 9 01:57:44.407962 systemd[1]: Finished Cleanup of Temporary Directories. Sep 9 01:57:44.407000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-tmpfiles-clean comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:57:44.413557 kernel: kauditd_printk_skb: 78 callbacks suppressed Sep 9 01:57:44.414117 kernel: audit: type=1130 audit(1788919064.407:254): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-tmpfiles-clean comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:57:44.411000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-tmpfiles-clean comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:57:44.439617 kernel: audit: type=1131 audit(1788919064.411:255): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-tmpfiles-clean comm="systemd" exe="/usr/lib64/systemd/systemd" hostname=? addr=? terminal=? res=success'