Oct 2 19:13:58.044207 kernel: Linux version 5.15.132-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 11.3.1_p20221209 p3) 11.3.1 20221209, GNU ld (Gentoo 2.39 p5) 2.39.0) #1 SMP Mon Oct 2 17:52:37 -00 2023 Oct 2 19:13:58.044231 kernel: Command line: BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=96b0fdb9f11bf1422adc9955c78c8182df387766badfd0b94e08fb9688739ee1 Oct 2 19:13:58.044241 kernel: BIOS-provided physical RAM map: Oct 2 19:13:58.044249 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Oct 2 19:13:58.044256 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Oct 2 19:13:58.044264 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Oct 2 19:13:58.044274 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Oct 2 19:13:58.044284 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Oct 2 19:13:58.044291 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Oct 2 19:13:58.044299 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Oct 2 19:13:58.044307 kernel: BIOS-e820: [mem 0x0000000100000000-0x00000002bfffffff] usable Oct 2 19:13:58.044317 kernel: printk: bootconsole [earlyser0] enabled Oct 2 19:13:58.044327 kernel: NX (Execute Disable) protection: active Oct 2 19:13:58.044337 kernel: efi: EFI v2.70 by Microsoft Oct 2 19:13:58.044350 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5caa98 RNG=0x3ffd1018 Oct 2 19:13:58.054378 kernel: random: crng init done Oct 2 19:13:58.054391 kernel: SMBIOS 3.1.0 present. Oct 2 19:13:58.054400 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 05/09/2022 Oct 2 19:13:58.054407 kernel: Hypervisor detected: Microsoft Hyper-V Oct 2 19:13:58.054414 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x64e24, misc 0xbed7b2 Oct 2 19:13:58.054420 kernel: Hyper-V Host Build:20348-10.0-1-0.1462 Oct 2 19:13:58.054426 kernel: Hyper-V: Nested features: 0x1e0101 Oct 2 19:13:58.054440 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Oct 2 19:13:58.054446 kernel: Hyper-V: Using hypercall for remote TLB flush Oct 2 19:13:58.054456 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Oct 2 19:13:58.054462 kernel: tsc: Marking TSC unstable due to running on Hyper-V Oct 2 19:13:58.054469 kernel: tsc: Detected 2593.905 MHz processor Oct 2 19:13:58.054477 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Oct 2 19:13:58.054486 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Oct 2 19:13:58.054494 kernel: last_pfn = 0x2c0000 max_arch_pfn = 0x400000000 Oct 2 19:13:58.054502 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Oct 2 19:13:58.054509 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Oct 2 19:13:58.054518 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Oct 2 19:13:58.054527 kernel: Using GB pages for direct mapping Oct 2 19:13:58.054534 kernel: Secure boot disabled Oct 2 19:13:58.054543 kernel: ACPI: Early table checksum verification disabled Oct 2 19:13:58.054550 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Oct 2 19:13:58.054556 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 2 19:13:58.054562 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 2 19:13:58.054569 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Oct 2 19:13:58.054581 kernel: ACPI: FACS 0x000000003FFFE000 000040 Oct 2 19:13:58.054590 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 2 19:13:58.054598 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 2 19:13:58.054606 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 2 19:13:58.054615 kernel: ACPI: APIC 0x000000003FFD5000 000058 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 2 19:13:58.054622 kernel: ACPI: SRAT 0x000000003FFD4000 0002D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 2 19:13:58.054632 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 2 19:13:58.054641 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 2 19:13:58.054649 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Oct 2 19:13:58.054658 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Oct 2 19:13:58.054665 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Oct 2 19:13:58.054672 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Oct 2 19:13:58.054679 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Oct 2 19:13:58.054688 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Oct 2 19:13:58.054707 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5057] Oct 2 19:13:58.054714 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd42cf] Oct 2 19:13:58.054721 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Oct 2 19:13:58.054728 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Oct 2 19:13:58.054738 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 Oct 2 19:13:58.054745 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 Oct 2 19:13:58.054756 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Oct 2 19:13:58.054763 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0x2bfffffff] hotplug Oct 2 19:13:58.054770 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x2c0000000-0xfdfffffff] hotplug Oct 2 19:13:58.054782 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x1000000000-0xffffffffff] hotplug Oct 2 19:13:58.054790 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000000000-0x1ffffffffff] hotplug Oct 2 19:13:58.054800 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000000000-0x3ffffffffff] hotplug Oct 2 19:13:58.054807 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000000000-0x7ffffffffff] hotplug Oct 2 19:13:58.054814 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000000000-0xfffffffffff] hotplug Oct 2 19:13:58.054824 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000000-0x1fffffffffff] hotplug Oct 2 19:13:58.054832 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x200000000000-0x3fffffffffff] hotplug Oct 2 19:13:58.054841 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x400000000000-0x7fffffffffff] hotplug Oct 2 19:13:58.054848 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x800000000000-0xffffffffffff] hotplug Oct 2 19:13:58.054860 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x1000000000000-0x1ffffffffffff] hotplug Oct 2 19:13:58.054867 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x2000000000000-0x3ffffffffffff] hotplug Oct 2 19:13:58.054877 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4000000000000-0x7ffffffffffff] hotplug Oct 2 19:13:58.054884 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x8000000000000-0xfffffffffffff] hotplug Oct 2 19:13:58.054892 kernel: NUMA: Node 0 [mem 0x00000000-0x3fffffff] + [mem 0x100000000-0x2bfffffff] -> [mem 0x00000000-0x2bfffffff] Oct 2 19:13:58.054901 kernel: NODE_DATA(0) allocated [mem 0x2bfffa000-0x2bfffffff] Oct 2 19:13:58.054909 kernel: Zone ranges: Oct 2 19:13:58.054918 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Oct 2 19:13:58.054925 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Oct 2 19:13:58.054937 kernel: Normal [mem 0x0000000100000000-0x00000002bfffffff] Oct 2 19:13:58.054944 kernel: Movable zone start for each node Oct 2 19:13:58.054952 kernel: Early memory node ranges Oct 2 19:13:58.054961 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Oct 2 19:13:58.054968 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Oct 2 19:13:58.054975 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Oct 2 19:13:58.054984 kernel: node 0: [mem 0x0000000100000000-0x00000002bfffffff] Oct 2 19:13:58.054991 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x00000002bfffffff] Oct 2 19:13:58.055002 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Oct 2 19:13:58.055011 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Oct 2 19:13:58.055018 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Oct 2 19:13:58.055028 kernel: ACPI: PM-Timer IO Port: 0x408 Oct 2 19:13:58.055035 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Oct 2 19:13:58.055043 kernel: IOAPIC[0]: apic_id 2, version 17, address 0xfec00000, GSI 0-23 Oct 2 19:13:58.055052 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Oct 2 19:13:58.055059 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Oct 2 19:13:58.055066 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Oct 2 19:13:58.055076 kernel: smpboot: Allowing 2 CPUs, 0 hotplug CPUs Oct 2 19:13:58.055085 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Oct 2 19:13:58.055095 kernel: Booting paravirtualized kernel on Hyper-V Oct 2 19:13:58.055102 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Oct 2 19:13:58.055109 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:512 nr_cpu_ids:2 nr_node_ids:1 Oct 2 19:13:58.055119 kernel: percpu: Embedded 55 pages/cpu s185624 r8192 d31464 u1048576 Oct 2 19:13:58.055126 kernel: pcpu-alloc: s185624 r8192 d31464 u1048576 alloc=1*2097152 Oct 2 19:13:58.055136 kernel: pcpu-alloc: [0] 0 1 Oct 2 19:13:58.055143 kernel: Hyper-V: PV spinlocks enabled Oct 2 19:13:58.055150 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Oct 2 19:13:58.055158 kernel: Built 1 zonelists, mobility grouping on. Total pages: 2062618 Oct 2 19:13:58.055167 kernel: Policy zone: Normal Oct 2 19:13:58.055177 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=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=96b0fdb9f11bf1422adc9955c78c8182df387766badfd0b94e08fb9688739ee1 Oct 2 19:13:58.055184 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Oct 2 19:13:58.055191 kernel: Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear) Oct 2 19:13:58.055198 kernel: Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 2 19:13:58.055208 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 2 19:13:58.055215 kernel: Memory: 8081204K/8387460K available (12294K kernel code, 2274K rwdata, 13692K rodata, 45372K init, 4176K bss, 305996K reserved, 0K cma-reserved) Oct 2 19:13:58.055226 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Oct 2 19:13:58.055235 kernel: ftrace: allocating 34453 entries in 135 pages Oct 2 19:13:58.055251 kernel: ftrace: allocated 135 pages with 4 groups Oct 2 19:13:58.055261 kernel: rcu: Hierarchical RCU implementation. Oct 2 19:13:58.055272 kernel: rcu: RCU event tracing is enabled. Oct 2 19:13:58.055280 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Oct 2 19:13:58.055291 kernel: Rude variant of Tasks RCU enabled. Oct 2 19:13:58.055298 kernel: Tracing variant of Tasks RCU enabled. Oct 2 19:13:58.055307 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 2 19:13:58.055316 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Oct 2 19:13:58.055325 kernel: Using NULL legacy PIC Oct 2 19:13:58.055336 kernel: NR_IRQS: 33024, nr_irqs: 440, preallocated irqs: 0 Oct 2 19:13:58.055344 kernel: Console: colour dummy device 80x25 Oct 2 19:13:58.055354 kernel: printk: console [tty1] enabled Oct 2 19:13:58.055362 kernel: printk: console [ttyS0] enabled Oct 2 19:13:58.055371 kernel: printk: bootconsole [earlyser0] disabled Oct 2 19:13:58.055381 kernel: ACPI: Core revision 20210730 Oct 2 19:13:58.055392 kernel: Failed to register legacy timer interrupt Oct 2 19:13:58.055399 kernel: APIC: Switch to symmetric I/O mode setup Oct 2 19:13:58.055410 kernel: Hyper-V: Using IPI hypercalls Oct 2 19:13:58.055417 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.81 BogoMIPS (lpj=2593905) Oct 2 19:13:58.055426 kernel: Last level iTLB entries: 4KB 64, 2MB 8, 4MB 8 Oct 2 19:13:58.055435 kernel: Last level dTLB entries: 4KB 64, 2MB 0, 4MB 0, 1GB 4 Oct 2 19:13:58.055444 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Oct 2 19:13:58.055453 kernel: Spectre V2 : Mitigation: Retpolines Oct 2 19:13:58.055460 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch Oct 2 19:13:58.055472 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT Oct 2 19:13:58.055480 kernel: RETBleed: WARNING: Spectre v2 mitigation leaves CPU vulnerable to RETBleed attacks, data leaks possible! Oct 2 19:13:58.055490 kernel: RETBleed: Vulnerable Oct 2 19:13:58.055497 kernel: Speculative Store Bypass: Vulnerable Oct 2 19:13:58.055505 kernel: TAA: Vulnerable: Clear CPU buffers attempted, no microcode Oct 2 19:13:58.055514 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Oct 2 19:13:58.055523 kernel: GDS: Unknown: Dependent on hypervisor status Oct 2 19:13:58.055532 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Oct 2 19:13:58.055539 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Oct 2 19:13:58.055549 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Oct 2 19:13:58.055559 kernel: x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask' Oct 2 19:13:58.055570 kernel: x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256' Oct 2 19:13:58.055577 kernel: x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256' Oct 2 19:13:58.055585 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Oct 2 19:13:58.055594 kernel: x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64 Oct 2 19:13:58.055603 kernel: x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512 Oct 2 19:13:58.055612 kernel: x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024 Oct 2 19:13:58.055619 kernel: x86/fpu: Enabled xstate features 0xe7, context size is 2432 bytes, using 'compacted' format. Oct 2 19:13:58.055629 kernel: Freeing SMP alternatives memory: 32K Oct 2 19:13:58.055636 kernel: pid_max: default: 32768 minimum: 301 Oct 2 19:13:58.055646 kernel: LSM: Security Framework initializing Oct 2 19:13:58.055653 kernel: SELinux: Initializing. Oct 2 19:13:58.055664 kernel: Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear) Oct 2 19:13:58.055673 kernel: Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear) Oct 2 19:13:58.055682 kernel: smpboot: CPU0: Intel(R) Xeon(R) Platinum 8272CL CPU @ 2.60GHz (family: 0x6, model: 0x55, stepping: 0x7) Oct 2 19:13:58.055696 kernel: Performance Events: unsupported p6 CPU model 85 no PMU driver, software events only. Oct 2 19:13:58.055706 kernel: signal: max sigframe size: 3632 Oct 2 19:13:58.055715 kernel: rcu: Hierarchical SRCU implementation. Oct 2 19:13:58.055724 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Oct 2 19:13:58.055733 kernel: smp: Bringing up secondary CPUs ... Oct 2 19:13:58.055740 kernel: x86: Booting SMP configuration: Oct 2 19:13:58.055750 kernel: .... node #0, CPUs: #1 Oct 2 19:13:58.055760 kernel: TAA CPU bug present and SMT on, data leak possible. See https://www.kernel.org/doc/html/latest/admin-guide/hw-vuln/tsx_async_abort.html for more details. Oct 2 19:13:58.055771 kernel: MMIO Stale Data CPU bug present and SMT on, data leak possible. See https://www.kernel.org/doc/html/latest/admin-guide/hw-vuln/processor_mmio_stale_data.html for more details. Oct 2 19:13:58.055778 kernel: smp: Brought up 1 node, 2 CPUs Oct 2 19:13:58.055787 kernel: smpboot: Max logical packages: 1 Oct 2 19:13:58.055796 kernel: smpboot: Total of 2 processors activated (10375.62 BogoMIPS) Oct 2 19:13:58.055803 kernel: devtmpfs: initialized Oct 2 19:13:58.055813 kernel: x86/mm: Memory block size: 128MB Oct 2 19:13:58.055821 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Oct 2 19:13:58.055832 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 2 19:13:58.055841 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Oct 2 19:13:58.055850 kernel: pinctrl core: initialized pinctrl subsystem Oct 2 19:13:58.055859 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Oct 2 19:13:58.055866 kernel: audit: initializing netlink subsys (disabled) Oct 2 19:13:58.055877 kernel: audit: type=2000 audit(1696274037.040:1): state=initialized audit_enabled=0 res=1 Oct 2 19:13:58.055884 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 2 19:13:58.055895 kernel: thermal_sys: Registered thermal governor 'user_space' Oct 2 19:13:58.055902 kernel: cpuidle: using governor menu Oct 2 19:13:58.055911 kernel: ACPI: bus type PCI registered Oct 2 19:13:58.055922 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 2 19:13:58.055930 kernel: dca service started, version 1.12.1 Oct 2 19:13:58.055940 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Oct 2 19:13:58.055947 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Oct 2 19:13:58.055956 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Oct 2 19:13:58.055965 kernel: ACPI: Added _OSI(Module Device) Oct 2 19:13:58.055973 kernel: ACPI: Added _OSI(Processor Device) Oct 2 19:13:58.055982 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Oct 2 19:13:58.055992 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 2 19:13:58.056003 kernel: ACPI: Added _OSI(Linux-Dell-Video) Oct 2 19:13:58.056011 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Oct 2 19:13:58.056021 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Oct 2 19:13:58.056028 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 2 19:13:58.056035 kernel: ACPI: Interpreter enabled Oct 2 19:13:58.056043 kernel: ACPI: PM: (supports S0 S5) Oct 2 19:13:58.056051 kernel: ACPI: Using IOAPIC for interrupt routing Oct 2 19:13:58.056060 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Oct 2 19:13:58.056072 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Oct 2 19:13:58.056080 kernel: iommu: Default domain type: Translated Oct 2 19:13:58.056087 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Oct 2 19:13:58.056098 kernel: vgaarb: loaded Oct 2 19:13:58.056105 kernel: pps_core: LinuxPPS API ver. 1 registered Oct 2 19:13:58.056115 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Oct 2 19:13:58.056123 kernel: PTP clock support registered Oct 2 19:13:58.056130 kernel: Registered efivars operations Oct 2 19:13:58.056140 kernel: PCI: Using ACPI for IRQ routing Oct 2 19:13:58.056148 kernel: PCI: System does not support PCI Oct 2 19:13:58.056160 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Oct 2 19:13:58.056167 kernel: VFS: Disk quotas dquot_6.6.0 Oct 2 19:13:58.056176 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 2 19:13:58.056185 kernel: pnp: PnP ACPI init Oct 2 19:13:58.056194 kernel: pnp: PnP ACPI: found 3 devices Oct 2 19:13:58.056202 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Oct 2 19:13:58.056210 kernel: NET: Registered PF_INET protocol family Oct 2 19:13:58.056220 kernel: IP idents hash table entries: 131072 (order: 8, 1048576 bytes, linear) Oct 2 19:13:58.056230 kernel: tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear) Oct 2 19:13:58.056240 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 2 19:13:58.056248 kernel: TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 2 19:13:58.056257 kernel: TCP bind hash table entries: 65536 (order: 8, 1048576 bytes, linear) Oct 2 19:13:58.056265 kernel: TCP: Hash tables configured (established 65536 bind 65536) Oct 2 19:13:58.056274 kernel: UDP hash table entries: 4096 (order: 5, 131072 bytes, linear) Oct 2 19:13:58.056283 kernel: UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, linear) Oct 2 19:13:58.056290 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Oct 2 19:13:58.056301 kernel: NET: Registered PF_XDP protocol family Oct 2 19:13:58.056311 kernel: PCI: CLS 0 bytes, default 64 Oct 2 19:13:58.056321 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Oct 2 19:13:58.056328 kernel: software IO TLB: mapped [mem 0x000000003a8ad000-0x000000003e8ad000] (64MB) Oct 2 19:13:58.056337 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Oct 2 19:13:58.056346 kernel: Initialise system trusted keyrings Oct 2 19:13:58.056354 kernel: workingset: timestamp_bits=39 max_order=21 bucket_order=0 Oct 2 19:13:58.056363 kernel: Key type asymmetric registered Oct 2 19:13:58.056371 kernel: Asymmetric key parser 'x509' registered Oct 2 19:13:58.056380 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Oct 2 19:13:58.056390 kernel: io scheduler mq-deadline registered Oct 2 19:13:58.056401 kernel: io scheduler kyber registered Oct 2 19:13:58.056408 kernel: io scheduler bfq registered Oct 2 19:13:58.056416 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Oct 2 19:13:58.056426 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 2 19:13:58.056434 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Oct 2 19:13:58.056443 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Oct 2 19:13:58.056451 kernel: i8042: PNP: No PS/2 controller found. Oct 2 19:13:58.056607 kernel: rtc_cmos 00:02: registered as rtc0 Oct 2 19:13:58.056708 kernel: rtc_cmos 00:02: setting system clock to 2023-10-02T19:13:57 UTC (1696274037) Oct 2 19:13:58.056793 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Oct 2 19:13:58.056803 kernel: fail to initialize ptp_kvm Oct 2 19:13:58.056814 kernel: intel_pstate: CPU model not supported Oct 2 19:13:58.056822 kernel: efifb: probing for efifb Oct 2 19:13:58.056832 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Oct 2 19:13:58.056839 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Oct 2 19:13:58.056852 kernel: efifb: scrolling: redraw Oct 2 19:13:58.056866 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Oct 2 19:13:58.056873 kernel: Console: switching to colour frame buffer device 128x48 Oct 2 19:13:58.056883 kernel: fb0: EFI VGA frame buffer device Oct 2 19:13:58.056892 kernel: pstore: Registered efi as persistent store backend Oct 2 19:13:58.056901 kernel: NET: Registered PF_INET6 protocol family Oct 2 19:13:58.056910 kernel: Segment Routing with IPv6 Oct 2 19:13:58.056920 kernel: In-situ OAM (IOAM) with IPv6 Oct 2 19:13:58.056930 kernel: NET: Registered PF_PACKET protocol family Oct 2 19:13:58.056938 kernel: Key type dns_resolver registered Oct 2 19:13:58.056951 kernel: IPI shorthand broadcast: enabled Oct 2 19:13:58.056961 kernel: sched_clock: Marking stable (729442400, 19741800)->(910457100, -161272900) Oct 2 19:13:58.056970 kernel: registered taskstats version 1 Oct 2 19:13:58.056978 kernel: Loading compiled-in X.509 certificates Oct 2 19:13:58.056988 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.15.132-flatcar: 6f9e51af8b3ef67eb6e93ecfe77d55665ad3d861' Oct 2 19:13:58.056998 kernel: Key type .fscrypt registered Oct 2 19:13:58.057005 kernel: Key type fscrypt-provisioning registered Oct 2 19:13:58.057014 kernel: pstore: Using crash dump compression: deflate Oct 2 19:13:58.057026 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 2 19:13:58.057036 kernel: ima: Allocated hash algorithm: sha1 Oct 2 19:13:58.057043 kernel: ima: No architecture policies found Oct 2 19:13:58.057054 kernel: Freeing unused kernel image (initmem) memory: 45372K Oct 2 19:13:58.057063 kernel: Write protecting the kernel read-only data: 28672k Oct 2 19:13:58.057072 kernel: Freeing unused kernel image (text/rodata gap) memory: 2040K Oct 2 19:13:58.057084 kernel: Freeing unused kernel image (rodata/data gap) memory: 644K Oct 2 19:13:58.057098 kernel: Run /init as init process Oct 2 19:13:58.057113 kernel: with arguments: Oct 2 19:13:58.057127 kernel: /init Oct 2 19:13:58.057144 kernel: with environment: Oct 2 19:13:58.057158 kernel: HOME=/ Oct 2 19:13:58.057173 kernel: TERM=linux Oct 2 19:13:58.057187 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 2 19:13:58.057205 systemd[1]: systemd 252 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE -TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Oct 2 19:13:58.057225 systemd[1]: Detected virtualization microsoft. Oct 2 19:13:58.057240 systemd[1]: Detected architecture x86-64. Oct 2 19:13:58.057258 systemd[1]: Running in initrd. Oct 2 19:13:58.057274 systemd[1]: No hostname configured, using default hostname. Oct 2 19:13:58.057289 systemd[1]: Hostname set to . Oct 2 19:13:58.057305 systemd[1]: Initializing machine ID from random generator. Oct 2 19:13:58.057321 systemd[1]: Queued start job for default target initrd.target. Oct 2 19:13:58.057336 systemd[1]: Started systemd-ask-password-console.path. Oct 2 19:13:58.057351 systemd[1]: Reached target cryptsetup.target. Oct 2 19:13:58.057366 systemd[1]: Reached target paths.target. Oct 2 19:13:58.057380 systemd[1]: Reached target slices.target. Oct 2 19:13:58.057401 systemd[1]: Reached target swap.target. Oct 2 19:13:58.057415 systemd[1]: Reached target timers.target. Oct 2 19:13:58.057431 systemd[1]: Listening on iscsid.socket. Oct 2 19:13:58.057445 systemd[1]: Listening on iscsiuio.socket. Oct 2 19:13:58.057460 systemd[1]: Listening on systemd-journald-audit.socket. Oct 2 19:13:58.057477 systemd[1]: Listening on systemd-journald-dev-log.socket. Oct 2 19:13:58.057491 systemd[1]: Listening on systemd-journald.socket. Oct 2 19:13:58.057513 systemd[1]: Listening on systemd-networkd.socket. Oct 2 19:13:58.057531 systemd[1]: Listening on systemd-udevd-control.socket. Oct 2 19:13:58.057547 systemd[1]: Listening on systemd-udevd-kernel.socket. Oct 2 19:13:58.057565 systemd[1]: Reached target sockets.target. Oct 2 19:13:58.057580 systemd[1]: Starting kmod-static-nodes.service... Oct 2 19:13:58.057597 systemd[1]: Finished network-cleanup.service. Oct 2 19:13:58.057612 systemd[1]: Starting systemd-fsck-usr.service... Oct 2 19:13:58.057629 systemd[1]: Starting systemd-journald.service... Oct 2 19:13:58.057645 systemd[1]: Starting systemd-modules-load.service... Oct 2 19:13:58.057663 systemd[1]: Starting systemd-resolved.service... Oct 2 19:13:58.057678 systemd[1]: Starting systemd-vconsole-setup.service... Oct 2 19:13:58.057701 systemd[1]: Finished kmod-static-nodes.service. Oct 2 19:13:58.057717 kernel: audit: type=1130 audit(1696274038.044:2): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:13:58.057733 systemd[1]: Finished systemd-fsck-usr.service. Oct 2 19:13:58.057755 systemd-journald[183]: Journal started Oct 2 19:13:58.057836 systemd-journald[183]: Runtime Journal (/run/log/journal/77a126844bbd489797fdc1b55ca36730) is 8.0M, max 159.0M, 151.0M free. Oct 2 19:13:58.044000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:13:58.024061 systemd-modules-load[184]: Inserted module 'overlay' Oct 2 19:13:58.096506 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Oct 2 19:13:58.096558 systemd[1]: Started systemd-journald.service. Oct 2 19:13:58.096575 kernel: audit: type=1130 audit(1696274038.082:3): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-usr comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:13:58.082000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-usr comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:13:58.092925 systemd-resolved[185]: Positive Trust Anchors: Oct 2 19:13:58.146281 kernel: audit: type=1130 audit(1696274038.100:4): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:13:58.146320 kernel: audit: type=1130 audit(1696274038.113:5): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:13:58.146337 kernel: Bridge firewalling registered Oct 2 19:13:58.146354 kernel: audit: type=1130 audit(1696274038.124:6): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:13:58.100000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:13:58.113000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:13:58.124000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:13:58.092937 systemd-resolved[185]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 2 19:13:58.092973 systemd-resolved[185]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Oct 2 19:13:58.095619 systemd-resolved[185]: Defaulting to hostname 'linux'. Oct 2 19:13:58.101329 systemd[1]: Started systemd-resolved.service. Oct 2 19:13:58.186983 kernel: audit: type=1130 audit(1696274038.173:7): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:13:58.173000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:13:58.113937 systemd[1]: Finished systemd-vconsole-setup.service. Oct 2 19:13:58.191489 kernel: SCSI subsystem initialized Oct 2 19:13:58.125877 systemd[1]: Reached target nss-lookup.target. Oct 2 19:13:58.139508 systemd-modules-load[184]: Inserted module 'br_netfilter' Oct 2 19:13:58.142286 systemd[1]: Starting dracut-cmdline-ask.service... Oct 2 19:13:58.195000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:13:58.145520 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... Oct 2 19:13:58.218603 kernel: audit: type=1130 audit(1696274038.195:8): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:13:58.218658 dracut-cmdline[201]: dracut-dracut-053 Oct 2 19:13:58.174531 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. Oct 2 19:13:58.233631 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Oct 2 19:13:58.233833 dracut-cmdline[201]: 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=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=96b0fdb9f11bf1422adc9955c78c8182df387766badfd0b94e08fb9688739ee1 Oct 2 19:13:58.193515 systemd[1]: Finished dracut-cmdline-ask.service. Oct 2 19:13:58.207809 systemd[1]: Starting dracut-cmdline.service... Oct 2 19:13:58.256913 kernel: device-mapper: uevent: version 1.0.3 Oct 2 19:13:58.256961 kernel: device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com Oct 2 19:13:58.261119 systemd-modules-load[184]: Inserted module 'dm_multipath' Oct 2 19:13:58.263787 systemd[1]: Finished systemd-modules-load.service. Oct 2 19:13:58.267000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:13:58.281773 kernel: audit: type=1130 audit(1696274038.267:9): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:13:58.280942 systemd[1]: Starting systemd-sysctl.service... Oct 2 19:13:58.293541 systemd[1]: Finished systemd-sysctl.service. Oct 2 19:13:58.295000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:13:58.307719 kernel: audit: type=1130 audit(1696274038.295:10): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:13:58.324713 kernel: Loading iSCSI transport class v2.0-870. Oct 2 19:13:58.338716 kernel: iscsi: registered transport (tcp) Oct 2 19:13:58.362413 kernel: iscsi: registered transport (qla4xxx) Oct 2 19:13:58.362482 kernel: QLogic iSCSI HBA Driver Oct 2 19:13:58.391940 systemd[1]: Finished dracut-cmdline.service. Oct 2 19:13:58.395000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:13:58.396894 systemd[1]: Starting dracut-pre-udev.service... Oct 2 19:13:58.446719 kernel: raid6: avx512x4 gen() 18437 MB/s Oct 2 19:13:58.466711 kernel: raid6: avx512x4 xor() 8752 MB/s Oct 2 19:13:58.486707 kernel: raid6: avx512x2 gen() 18437 MB/s Oct 2 19:13:58.506712 kernel: raid6: avx512x2 xor() 29818 MB/s Oct 2 19:13:58.526707 kernel: raid6: avx512x1 gen() 18461 MB/s Oct 2 19:13:58.546708 kernel: raid6: avx512x1 xor() 26995 MB/s Oct 2 19:13:58.566716 kernel: raid6: avx2x4 gen() 18357 MB/s Oct 2 19:13:58.586708 kernel: raid6: avx2x4 xor() 8091 MB/s Oct 2 19:13:58.606704 kernel: raid6: avx2x2 gen() 18410 MB/s Oct 2 19:13:58.626709 kernel: raid6: avx2x2 xor() 22253 MB/s Oct 2 19:13:58.646705 kernel: raid6: avx2x1 gen() 14000 MB/s Oct 2 19:13:58.666706 kernel: raid6: avx2x1 xor() 19426 MB/s Oct 2 19:13:58.687707 kernel: raid6: sse2x4 gen() 11742 MB/s Oct 2 19:13:58.707715 kernel: raid6: sse2x4 xor() 7294 MB/s Oct 2 19:13:58.727704 kernel: raid6: sse2x2 gen() 12944 MB/s Oct 2 19:13:58.748709 kernel: raid6: sse2x2 xor() 7730 MB/s Oct 2 19:13:58.768705 kernel: raid6: sse2x1 gen() 11658 MB/s Oct 2 19:13:58.791066 kernel: raid6: sse2x1 xor() 5937 MB/s Oct 2 19:13:58.791084 kernel: raid6: using algorithm avx512x1 gen() 18461 MB/s Oct 2 19:13:58.791098 kernel: raid6: .... xor() 26995 MB/s, rmw enabled Oct 2 19:13:58.793928 kernel: raid6: using avx512x2 recovery algorithm Oct 2 19:13:58.813712 kernel: xor: automatically using best checksumming function avx Oct 2 19:13:58.907720 kernel: Btrfs loaded, crc32c=crc32c-intel, zoned=no, fsverity=no Oct 2 19:13:58.916289 systemd[1]: Finished dracut-pre-udev.service. Oct 2 19:13:58.926000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:13:58.927000 audit: BPF prog-id=7 op=LOAD Oct 2 19:13:58.927000 audit: BPF prog-id=8 op=LOAD Oct 2 19:13:58.928457 systemd[1]: Starting systemd-udevd.service... Oct 2 19:13:58.941983 systemd-udevd[383]: Using default interface naming scheme 'v252'. Oct 2 19:13:58.946723 systemd[1]: Started systemd-udevd.service. Oct 2 19:13:58.948000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:13:58.949707 systemd[1]: Starting dracut-pre-trigger.service... Oct 2 19:13:58.969310 dracut-pre-trigger[387]: rd.md=0: removing MD RAID activation Oct 2 19:13:58.999233 systemd[1]: Finished dracut-pre-trigger.service. Oct 2 19:13:59.000000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:13:59.002302 systemd[1]: Starting systemd-udev-trigger.service... Oct 2 19:13:59.043000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:13:59.041790 systemd[1]: Finished systemd-udev-trigger.service. Oct 2 19:13:59.096605 kernel: hv_vmbus: Vmbus version:5.2 Oct 2 19:13:59.096666 kernel: cryptd: max_cpu_qlen set to 1000 Oct 2 19:13:59.125801 kernel: hv_vmbus: registering driver hyperv_keyboard Oct 2 19:13:59.125865 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/VMBUS:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input0 Oct 2 19:13:59.132710 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 2 19:13:59.145444 kernel: hv_vmbus: registering driver hid_hyperv Oct 2 19:13:59.145504 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Oct 2 19:13:59.153713 kernel: hid 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Oct 2 19:13:59.160528 kernel: AVX2 version of gcm_enc/dec engaged. Oct 2 19:13:59.160585 kernel: AES CTR mode by8 optimization enabled Oct 2 19:13:59.165708 kernel: hv_vmbus: registering driver hv_netvsc Oct 2 19:13:59.184719 kernel: hv_vmbus: registering driver hv_storvsc Oct 2 19:13:59.193514 kernel: scsi host1: storvsc_host_t Oct 2 19:13:59.193712 kernel: scsi host0: storvsc_host_t Oct 2 19:13:59.198713 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Oct 2 19:13:59.198775 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Oct 2 19:13:59.229151 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Oct 2 19:13:59.229385 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Oct 2 19:13:59.231720 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Oct 2 19:13:59.231896 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Oct 2 19:13:59.238685 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Oct 2 19:13:59.238874 kernel: sd 0:0:0:0: [sda] Write Protect is off Oct 2 19:13:59.246234 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Oct 2 19:13:59.246459 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Oct 2 19:13:59.251710 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Oct 2 19:13:59.255711 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Oct 2 19:13:59.316752 kernel: hv_netvsc 000d3ad9-7de3-000d-3ad9-7de3000d3ad9 eth0: VF slot 1 added Oct 2 19:13:59.326715 kernel: hv_vmbus: registering driver hv_pci Oct 2 19:13:59.331709 kernel: hv_pci 9253fe44-0f45-45dd-b102-3fd69a130afb: PCI VMBus probing: Using version 0x10004 Oct 2 19:13:59.342596 kernel: hv_pci 9253fe44-0f45-45dd-b102-3fd69a130afb: PCI host bridge to bus 0f45:00 Oct 2 19:13:59.342789 kernel: pci_bus 0f45:00: root bus resource [mem 0xfe0000000-0xfe00fffff window] Oct 2 19:13:59.342937 kernel: pci_bus 0f45:00: No busn resource found for root bus, will use [bus 00-ff] Oct 2 19:13:59.354919 kernel: pci 0f45:00:02.0: [15b3:1016] type 00 class 0x020000 Oct 2 19:13:59.362641 kernel: pci 0f45:00:02.0: reg 0x10: [mem 0xfe0000000-0xfe00fffff 64bit pref] Oct 2 19:13:59.378102 kernel: pci 0f45:00:02.0: enabling Extended Tags Oct 2 19:13:59.394065 kernel: pci 0f45:00:02.0: 0.000 Gb/s available PCIe bandwidth, limited by Unknown x0 link at 0f45:00:02.0 (capable of 63.008 Gb/s with 8.0 GT/s PCIe x8 link) Oct 2 19:13:59.402784 kernel: pci_bus 0f45:00: busn_res: [bus 00-ff] end is updated to 00 Oct 2 19:13:59.402978 kernel: pci 0f45:00:02.0: BAR 0: assigned [mem 0xfe0000000-0xfe00fffff 64bit pref] Oct 2 19:13:59.500720 kernel: mlx5_core 0f45:00:02.0: firmware version: 14.30.1224 Oct 2 19:13:59.655786 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device. Oct 2 19:13:59.674723 kernel: mlx5_core 0f45:00:02.0: MLX5E: StrdRq(0) RqSz(1024) StrdSz(256) RxCqeCmprss(0) Oct 2 19:13:59.674935 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 scanned by (udev-worker) (440) Oct 2 19:13:59.693803 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Oct 2 19:13:59.836085 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device. Oct 2 19:13:59.842025 kernel: mlx5_core 0f45:00:02.0: Supported tc offload range - chains: 1, prios: 1 Oct 2 19:13:59.842256 kernel: mlx5_core 0f45:00:02.0: mlx5e_tc_post_act_init:40:(pid 188): firmware level support is missing Oct 2 19:13:59.842519 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device. Oct 2 19:13:59.853015 systemd[1]: Starting disk-uuid.service... Oct 2 19:13:59.875833 kernel: hv_netvsc 000d3ad9-7de3-000d-3ad9-7de3000d3ad9 eth0: VF registering: eth1 Oct 2 19:13:59.875985 kernel: mlx5_core 0f45:00:02.0 eth1: joined to eth0 Oct 2 19:13:59.887717 kernel: mlx5_core 0f45:00:02.0 enP3909s1: renamed from eth1 Oct 2 19:13:59.969536 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device. Oct 2 19:14:00.877719 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Oct 2 19:14:00.877793 disk-uuid[543]: The operation has completed successfully. Oct 2 19:14:00.961136 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 2 19:14:00.962000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=disk-uuid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:00.962000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=disk-uuid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:00.961242 systemd[1]: Finished disk-uuid.service. Oct 2 19:14:00.964216 systemd[1]: Starting verity-setup.service... Oct 2 19:14:01.002713 kernel: device-mapper: verity: sha256 using implementation "sha256-generic" Oct 2 19:14:01.252574 systemd[1]: Found device dev-mapper-usr.device. Oct 2 19:14:01.256851 systemd[1]: Finished verity-setup.service. Oct 2 19:14:01.259000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=verity-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:01.261079 systemd[1]: Mounting sysusr-usr.mount... Oct 2 19:14:01.338725 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: norecovery. Quota mode: none. Oct 2 19:14:01.338687 systemd[1]: Mounted sysusr-usr.mount. Oct 2 19:14:01.340608 systemd[1]: afterburn-network-kargs.service was skipped because no trigger condition checks were met. Oct 2 19:14:01.341378 systemd[1]: Starting ignition-setup.service... Oct 2 19:14:01.375974 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Oct 2 19:14:01.376006 kernel: BTRFS info (device sda6): using free space tree Oct 2 19:14:01.376026 kernel: BTRFS info (device sda6): has skinny extents Oct 2 19:14:01.351486 systemd[1]: Starting parse-ip-for-networkd.service... Oct 2 19:14:01.421333 systemd[1]: Finished parse-ip-for-networkd.service. Oct 2 19:14:01.424000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=parse-ip-for-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:01.425000 audit: BPF prog-id=9 op=LOAD Oct 2 19:14:01.426638 systemd[1]: Starting systemd-networkd.service... Oct 2 19:14:01.441139 systemd[1]: mnt-oem.mount: Deactivated successfully. Oct 2 19:14:01.456347 systemd-networkd[814]: lo: Link UP Oct 2 19:14:01.459000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:01.456357 systemd-networkd[814]: lo: Gained carrier Oct 2 19:14:01.456899 systemd-networkd[814]: Enumeration completed Oct 2 19:14:01.457437 systemd[1]: Started systemd-networkd.service. Oct 2 19:14:01.460029 systemd-networkd[814]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 2 19:14:01.460340 systemd[1]: Reached target network.target. Oct 2 19:14:01.470652 systemd[1]: Starting iscsiuio.service... Oct 2 19:14:01.476104 systemd[1]: Started iscsiuio.service. Oct 2 19:14:01.478000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsiuio comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:01.480162 systemd[1]: Starting iscsid.service... Oct 2 19:14:01.483596 iscsid[823]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Oct 2 19:14:01.483596 iscsid[823]: iscsid: Warning: InitiatorName file /etc/iscsi/initiatorname.iscsi does not exist or does not contain a properly formatted InitiatorName. If using software iscsi (iscsi_tcp or ib_iser) or partial offload (bnx2i or cxgbi iscsi), you may not be able to log into or discover targets. Please create a file /etc/iscsi/initiatorname.iscsi that contains a sting with the format: InitiatorName=iqn.yyyy-mm.[:identifier]. Oct 2 19:14:01.483596 iscsid[823]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Oct 2 19:14:01.483596 iscsid[823]: If using hardware iscsi like qla4xxx this message can be ignored. Oct 2 19:14:01.483596 iscsid[823]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Oct 2 19:14:01.483596 iscsid[823]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Oct 2 19:14:01.487000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:01.486594 systemd[1]: Started iscsid.service. Oct 2 19:14:01.518000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-initqueue comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:01.501366 systemd[1]: Starting dracut-initqueue.service... Oct 2 19:14:01.517678 systemd[1]: Finished dracut-initqueue.service. Oct 2 19:14:01.519824 systemd[1]: Reached target remote-fs-pre.target. Oct 2 19:14:01.521747 systemd[1]: Reached target remote-cryptsetup.target. Oct 2 19:14:01.523636 systemd[1]: Reached target remote-fs.target. Oct 2 19:14:01.539000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:01.527343 systemd[1]: Starting dracut-pre-mount.service... Oct 2 19:14:01.537928 systemd[1]: Finished dracut-pre-mount.service. Oct 2 19:14:01.552716 kernel: mlx5_core 0f45:00:02.0 enP3909s1: Link up Oct 2 19:14:01.609469 systemd[1]: Finished ignition-setup.service. Oct 2 19:14:01.613000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:01.614690 systemd[1]: Starting ignition-fetch-offline.service... Oct 2 19:14:01.626708 kernel: hv_netvsc 000d3ad9-7de3-000d-3ad9-7de3000d3ad9 eth0: Data path switched to VF: enP3909s1 Oct 2 19:14:01.626890 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Oct 2 19:14:01.630965 systemd-networkd[814]: enP3909s1: Link UP Oct 2 19:14:01.631098 systemd-networkd[814]: eth0: Link UP Oct 2 19:14:01.631314 systemd-networkd[814]: eth0: Gained carrier Oct 2 19:14:01.635893 systemd-networkd[814]: enP3909s1: Gained carrier Oct 2 19:14:01.674791 systemd-networkd[814]: eth0: DHCPv4 address 10.200.8.42/24, gateway 10.200.8.1 acquired from 168.63.129.16 Oct 2 19:14:03.300942 systemd-networkd[814]: eth0: Gained IPv6LL Oct 2 19:14:04.794263 ignition[838]: Ignition 2.14.0 Oct 2 19:14:04.794280 ignition[838]: Stage: fetch-offline Oct 2 19:14:04.794384 ignition[838]: reading system config file "/usr/lib/ignition/base.d/base.ign" Oct 2 19:14:04.794435 ignition[838]: parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Oct 2 19:14:04.828071 ignition[838]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 2 19:14:04.829583 ignition[838]: parsed url from cmdline: "" Oct 2 19:14:04.829591 ignition[838]: no config URL provided Oct 2 19:14:04.829601 ignition[838]: reading system config file "/usr/lib/ignition/user.ign" Oct 2 19:14:04.829611 ignition[838]: no config at "/usr/lib/ignition/user.ign" Oct 2 19:14:04.829617 ignition[838]: failed to fetch config: resource requires networking Oct 2 19:14:04.829788 ignition[838]: Ignition finished successfully Oct 2 19:14:04.841163 systemd[1]: Finished ignition-fetch-offline.service. Oct 2 19:14:04.863042 kernel: kauditd_printk_skb: 18 callbacks suppressed Oct 2 19:14:04.863076 kernel: audit: type=1130 audit(1696274044.844:29): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch-offline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:04.844000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch-offline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:04.845547 systemd[1]: Starting ignition-fetch.service... Oct 2 19:14:04.854376 ignition[845]: Ignition 2.14.0 Oct 2 19:14:04.854383 ignition[845]: Stage: fetch Oct 2 19:14:04.854483 ignition[845]: reading system config file "/usr/lib/ignition/base.d/base.ign" Oct 2 19:14:04.854509 ignition[845]: parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Oct 2 19:14:04.857882 ignition[845]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 2 19:14:04.907032 ignition[845]: parsed url from cmdline: "" Oct 2 19:14:04.907050 ignition[845]: no config URL provided Oct 2 19:14:04.907061 ignition[845]: reading system config file "/usr/lib/ignition/user.ign" Oct 2 19:14:04.907075 ignition[845]: no config at "/usr/lib/ignition/user.ign" Oct 2 19:14:04.907120 ignition[845]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Oct 2 19:14:04.931436 ignition[845]: GET result: OK Oct 2 19:14:04.931509 ignition[845]: config has been read from IMDS userdata Oct 2 19:14:04.931543 ignition[845]: parsing config with SHA512: 600f3a5e05e78da6b2b25c2117354fe88cae5b257cc40bbea5cb862697998451fce0e119fa1ce56c8b103b8cbc87aa2ba3c8745cd3f36de92e830abc76265bfe Oct 2 19:14:04.944422 ignition[845]: Adding "10.200.8.31" to list of CAs Oct 2 19:14:04.944468 ignition[845]: GET https://10.200.8.31: attempt #1 Oct 2 19:14:04.958404 ignition[845]: GET result: OK Oct 2 19:14:04.958444 ignition[845]: fetched referenced config at https://10.200.8.31 with SHA512: cc899705f00f5a069af318c604c43d77881b697732d6c6b7b2cd33a61a80745483eaa9fcb0ad489ab59fd94213023ab34bf4709b629cb63ff29457c98d049c9a Oct 2 19:14:04.960960 ignition[845]: Adding "10.200.8.31" to list of CAs Oct 2 19:14:04.962885 ignition[845]: Adding "10.200.8.31" to list of CAs Oct 2 19:14:04.965562 unknown[845]: fetched base config from "system" Oct 2 19:14:04.965575 unknown[845]: fetched base config from "system" Oct 2 19:14:04.965583 unknown[845]: fetched user config from "azure" Oct 2 19:14:04.965590 unknown[845]: fetched referenced user config from "" Oct 2 19:14:04.973148 ignition[845]: fetch: fetch complete Oct 2 19:14:04.973157 ignition[845]: fetch: fetch passed Oct 2 19:14:04.974596 ignition[845]: Ignition finished successfully Oct 2 19:14:04.978596 systemd[1]: Finished ignition-fetch.service. Oct 2 19:14:04.980000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:04.993727 kernel: audit: type=1130 audit(1696274044.980:30): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:04.992291 systemd[1]: Starting ignition-kargs.service... Oct 2 19:14:05.003895 ignition[851]: Ignition 2.14.0 Oct 2 19:14:05.003905 ignition[851]: Stage: kargs Oct 2 19:14:05.004040 ignition[851]: reading system config file "/usr/lib/ignition/base.d/base.ign" Oct 2 19:14:05.004078 ignition[851]: parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Oct 2 19:14:05.007401 ignition[851]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 2 19:14:05.019872 ignition[851]: Adding "10.200.8.31" to list of CAs Oct 2 19:14:05.020190 ignition[851]: kargs: kargs passed Oct 2 19:14:05.022953 systemd[1]: Finished ignition-kargs.service. Oct 2 19:14:05.024000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-kargs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:05.020238 ignition[851]: Ignition finished successfully Oct 2 19:14:05.040748 kernel: audit: type=1130 audit(1696274045.024:31): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-kargs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:05.026151 systemd[1]: Starting ignition-disks.service... Oct 2 19:14:05.046365 ignition[857]: Ignition 2.14.0 Oct 2 19:14:05.047742 ignition[857]: Stage: disks Oct 2 19:14:05.047879 ignition[857]: reading system config file "/usr/lib/ignition/base.d/base.ign" Oct 2 19:14:05.047910 ignition[857]: parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Oct 2 19:14:05.052812 ignition[857]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 2 19:14:05.065081 ignition[857]: Adding "10.200.8.31" to list of CAs Oct 2 19:14:05.065387 ignition[857]: disks: disks passed Oct 2 19:14:05.067939 systemd[1]: Finished ignition-disks.service. Oct 2 19:14:05.069000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-disks comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:05.065433 ignition[857]: Ignition finished successfully Oct 2 19:14:05.087566 kernel: audit: type=1130 audit(1696274045.069:32): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-disks comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:05.070490 systemd[1]: Reached target initrd-root-device.target. Oct 2 19:14:05.084122 systemd[1]: Reached target local-fs-pre.target. Oct 2 19:14:05.087564 systemd[1]: Reached target local-fs.target. Oct 2 19:14:05.090986 systemd[1]: Reached target sysinit.target. Oct 2 19:14:05.094422 systemd[1]: Reached target basic.target. Oct 2 19:14:05.101726 systemd[1]: Starting systemd-fsck-root.service... Oct 2 19:14:05.157894 systemd-fsck[865]: ROOT: clean, 603/7326000 files, 481068/7359488 blocks Oct 2 19:14:05.168349 systemd[1]: Finished systemd-fsck-root.service. Oct 2 19:14:05.191962 kernel: audit: type=1130 audit(1696274045.169:33): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:05.169000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:05.172481 systemd[1]: Mounting sysroot.mount... Oct 2 19:14:05.205775 kernel: EXT4-fs (sda9): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none. Oct 2 19:14:05.206206 systemd[1]: Mounted sysroot.mount. Oct 2 19:14:05.207904 systemd[1]: Reached target initrd-root-fs.target. Oct 2 19:14:05.247422 systemd[1]: Mounting sysroot-usr.mount... Oct 2 19:14:05.252826 systemd[1]: Starting flatcar-metadata-hostname.service... Oct 2 19:14:05.257770 systemd[1]: ignition-remount-sysroot.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Oct 2 19:14:05.257813 systemd[1]: Reached target ignition-diskful.target. Oct 2 19:14:05.264486 systemd[1]: Mounted sysroot-usr.mount. Oct 2 19:14:05.396349 systemd[1]: Mounting sysroot-usr-share-oem.mount... Oct 2 19:14:05.402028 systemd[1]: Starting initrd-setup-root.service... Oct 2 19:14:05.409718 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/sda6 scanned by mount (876) Oct 2 19:14:05.419048 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Oct 2 19:14:05.419088 kernel: BTRFS info (device sda6): using free space tree Oct 2 19:14:05.419102 kernel: BTRFS info (device sda6): has skinny extents Oct 2 19:14:05.426034 systemd[1]: Mounted sysroot-usr-share-oem.mount. Oct 2 19:14:05.429873 initrd-setup-root[881]: cut: /sysroot/etc/passwd: No such file or directory Oct 2 19:14:05.449680 initrd-setup-root[907]: cut: /sysroot/etc/group: No such file or directory Oct 2 19:14:05.473006 initrd-setup-root[915]: cut: /sysroot/etc/shadow: No such file or directory Oct 2 19:14:05.479434 initrd-setup-root[923]: cut: /sysroot/etc/gshadow: No such file or directory Oct 2 19:14:05.935059 systemd[1]: Finished initrd-setup-root.service. Oct 2 19:14:05.936000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:05.950260 kernel: audit: type=1130 audit(1696274045.936:34): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:05.949009 systemd[1]: Starting ignition-mount.service... Oct 2 19:14:05.951927 systemd[1]: Starting sysroot-boot.service... Oct 2 19:14:05.963744 systemd[1]: sysusr-usr-share-oem.mount: Deactivated successfully. Oct 2 19:14:05.964786 systemd[1]: sysroot-usr-share-oem.mount: Deactivated successfully. Oct 2 19:14:05.987157 systemd[1]: Finished sysroot-boot.service. Oct 2 19:14:05.993000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=sysroot-boot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:06.003080 ignition[943]: INFO : Ignition 2.14.0 Oct 2 19:14:06.003080 ignition[943]: INFO : Stage: mount Oct 2 19:14:06.003080 ignition[943]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign" Oct 2 19:14:06.003080 ignition[943]: DEBUG : parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Oct 2 19:14:06.017547 kernel: audit: type=1130 audit(1696274045.993:35): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=sysroot-boot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:06.017580 ignition[943]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 2 19:14:06.027979 ignition[943]: INFO : Adding "10.200.8.31" to list of CAs Oct 2 19:14:06.032276 ignition[943]: INFO : mount: mount passed Oct 2 19:14:06.032276 ignition[943]: INFO : Ignition finished successfully Oct 2 19:14:06.047751 kernel: audit: type=1130 audit(1696274046.031:36): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:06.031000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:06.030269 systemd[1]: Finished ignition-mount.service. Oct 2 19:14:06.785527 coreos-metadata[875]: Oct 02 19:14:06.785 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Oct 2 19:14:06.804048 coreos-metadata[875]: Oct 02 19:14:06.804 INFO Fetch successful Oct 2 19:14:06.839024 coreos-metadata[875]: Oct 02 19:14:06.838 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Oct 2 19:14:06.855350 coreos-metadata[875]: Oct 02 19:14:06.855 INFO Fetch successful Oct 2 19:14:06.871942 coreos-metadata[875]: Oct 02 19:14:06.871 INFO wrote hostname ci-3510.3.0-a-aff9975401 to /sysroot/etc/hostname Oct 2 19:14:06.874332 systemd[1]: Finished flatcar-metadata-hostname.service. Oct 2 19:14:06.877000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=flatcar-metadata-hostname comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:06.889742 systemd[1]: Starting ignition-files.service... Oct 2 19:14:06.895882 kernel: audit: type=1130 audit(1696274046.877:37): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=flatcar-metadata-hostname comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:06.901192 systemd[1]: Mounting sysroot-usr-share-oem.mount... Oct 2 19:14:06.914718 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/sda6 scanned by mount (954) Oct 2 19:14:06.914752 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Oct 2 19:14:06.922442 kernel: BTRFS info (device sda6): using free space tree Oct 2 19:14:06.922463 kernel: BTRFS info (device sda6): has skinny extents Oct 2 19:14:06.937760 systemd[1]: Mounted sysroot-usr-share-oem.mount. Oct 2 19:14:06.950742 ignition[973]: INFO : Ignition 2.14.0 Oct 2 19:14:06.950742 ignition[973]: INFO : Stage: files Oct 2 19:14:06.954238 ignition[973]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign" Oct 2 19:14:06.954238 ignition[973]: DEBUG : parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Oct 2 19:14:06.966443 ignition[973]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 2 19:14:06.978666 ignition[973]: INFO : Adding "10.200.8.31" to list of CAs Oct 2 19:14:06.995848 ignition[973]: DEBUG : files: compiled without relabeling support, skipping Oct 2 19:14:06.999333 ignition[973]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Oct 2 19:14:06.999333 ignition[973]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Oct 2 19:14:07.050184 ignition[973]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Oct 2 19:14:07.053871 ignition[973]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Oct 2 19:14:07.067507 unknown[973]: wrote ssh authorized keys file for user: core Oct 2 19:14:07.070079 ignition[973]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Oct 2 19:14:07.073285 ignition[973]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/var/resource/data" Oct 2 19:14:07.077059 ignition[973]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/var/resource/data" Oct 2 19:14:07.077059 ignition[973]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/systemd/system/waagent.service" Oct 2 19:14:07.077059 ignition[973]: INFO : files: createFilesystemsFiles: createFiles: op(4): oem config not found in "/usr/share/oem", looking on oem partition Oct 2 19:14:07.094391 kernel: BTRFS info: devid 1 device path /dev/sda6 changed to /dev/disk/by-label/OEM scanned by ignition (973) Oct 2 19:14:07.094418 ignition[973]: INFO : files: createFilesystemsFiles: createFiles: op(4): op(5): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem4290466288" Oct 2 19:14:07.094418 ignition[973]: CRITICAL : files: createFilesystemsFiles: createFiles: op(4): op(5): [failed] mounting "/dev/disk/by-label/OEM" at "/mnt/oem4290466288": device or resource busy Oct 2 19:14:07.094418 ignition[973]: ERROR : files: createFilesystemsFiles: createFiles: op(4): failed to mount ext4 device "/dev/disk/by-label/OEM" at "/mnt/oem4290466288", trying btrfs: device or resource busy Oct 2 19:14:07.094418 ignition[973]: INFO : files: createFilesystemsFiles: createFiles: op(4): op(6): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem4290466288" Oct 2 19:14:07.094418 ignition[973]: INFO : files: createFilesystemsFiles: createFiles: op(4): op(6): [finished] mounting "/dev/disk/by-label/OEM" at "/mnt/oem4290466288" Oct 2 19:14:07.118227 ignition[973]: INFO : files: createFilesystemsFiles: createFiles: op(4): op(7): [started] unmounting "/mnt/oem4290466288" Oct 2 19:14:07.118227 ignition[973]: INFO : files: createFilesystemsFiles: createFiles: op(4): op(7): [finished] unmounting "/mnt/oem4290466288" Oct 2 19:14:07.118227 ignition[973]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/systemd/system/waagent.service" Oct 2 19:14:07.118227 ignition[973]: INFO : files: createFilesystemsFiles: createFiles: op(8): [started] writing file "/sysroot/etc/systemd/system/nvidia.service" Oct 2 19:14:07.118227 ignition[973]: INFO : files: createFilesystemsFiles: createFiles: op(8): oem config not found in "/usr/share/oem", looking on oem partition Oct 2 19:14:07.118227 ignition[973]: INFO : files: createFilesystemsFiles: createFiles: op(8): op(9): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem1785803638" Oct 2 19:14:07.118227 ignition[973]: CRITICAL : files: createFilesystemsFiles: createFiles: op(8): op(9): [failed] mounting "/dev/disk/by-label/OEM" at "/mnt/oem1785803638": device or resource busy Oct 2 19:14:07.118227 ignition[973]: ERROR : files: createFilesystemsFiles: createFiles: op(8): failed to mount ext4 device "/dev/disk/by-label/OEM" at "/mnt/oem1785803638", trying btrfs: device or resource busy Oct 2 19:14:07.118227 ignition[973]: INFO : files: createFilesystemsFiles: createFiles: op(8): op(a): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem1785803638" Oct 2 19:14:07.118227 ignition[973]: INFO : files: createFilesystemsFiles: createFiles: op(8): op(a): [finished] mounting "/dev/disk/by-label/OEM" at "/mnt/oem1785803638" Oct 2 19:14:07.118227 ignition[973]: INFO : files: createFilesystemsFiles: createFiles: op(8): op(b): [started] unmounting "/mnt/oem1785803638" Oct 2 19:14:07.118227 ignition[973]: INFO : files: createFilesystemsFiles: createFiles: op(8): op(b): [finished] unmounting "/mnt/oem1785803638" Oct 2 19:14:07.118227 ignition[973]: INFO : files: createFilesystemsFiles: createFiles: op(8): [finished] writing file "/sysroot/etc/systemd/system/nvidia.service" Oct 2 19:14:07.118227 ignition[973]: INFO : files: op(c): [started] processing unit "nvidia.service" Oct 2 19:14:07.118227 ignition[973]: INFO : files: op(c): [finished] processing unit "nvidia.service" Oct 2 19:14:07.118227 ignition[973]: INFO : files: op(d): [started] processing unit "waagent.service" Oct 2 19:14:07.118227 ignition[973]: INFO : files: op(d): [finished] processing unit "waagent.service" Oct 2 19:14:07.215986 kernel: audit: type=1130 audit(1696274047.121:38): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.121000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.161000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root-after-ignition comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.168000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.168000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.201000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.201000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.097127 systemd[1]: mnt-oem4290466288.mount: Deactivated successfully. Oct 2 19:14:07.218433 ignition[973]: INFO : files: op(e): [started] setting preset to enabled for "nvidia.service" Oct 2 19:14:07.218433 ignition[973]: INFO : files: op(e): [finished] setting preset to enabled for "nvidia.service" Oct 2 19:14:07.218433 ignition[973]: INFO : files: op(f): [started] setting preset to enabled for "waagent.service" Oct 2 19:14:07.218433 ignition[973]: INFO : files: op(f): [finished] setting preset to enabled for "waagent.service" Oct 2 19:14:07.218433 ignition[973]: INFO : files: createResultFile: createFiles: op(10): [started] writing file "/sysroot/etc/.ignition-result.json" Oct 2 19:14:07.218433 ignition[973]: INFO : files: createResultFile: createFiles: op(10): [finished] writing file "/sysroot/etc/.ignition-result.json" Oct 2 19:14:07.218433 ignition[973]: INFO : files: files passed Oct 2 19:14:07.218433 ignition[973]: INFO : Ignition finished successfully Oct 2 19:14:07.116679 systemd[1]: mnt-oem1785803638.mount: Deactivated successfully. Oct 2 19:14:07.121367 systemd[1]: Finished ignition-files.service. Oct 2 19:14:07.222725 initrd-setup-root-after-ignition[999]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 2 19:14:07.138811 systemd[1]: Starting initrd-setup-root-after-ignition.service... Oct 2 19:14:07.144807 systemd[1]: torcx-profile-populate.service was skipped because of an unmet condition check (ConditionPathExists=/sysroot/etc/torcx/next-profile). Oct 2 19:14:07.145582 systemd[1]: Starting ignition-quench.service... Oct 2 19:14:07.158907 systemd[1]: Finished initrd-setup-root-after-ignition.service. Oct 2 19:14:07.163929 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 2 19:14:07.164013 systemd[1]: Finished ignition-quench.service. Oct 2 19:14:07.170588 systemd[1]: Reached target ignition-complete.target. Oct 2 19:14:07.178431 systemd[1]: Starting initrd-parse-etc.service... Oct 2 19:14:07.197611 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 2 19:14:07.197715 systemd[1]: Finished initrd-parse-etc.service. Oct 2 19:14:07.201812 systemd[1]: Reached target initrd-fs.target. Oct 2 19:14:07.206048 systemd[1]: Reached target initrd.target. Oct 2 19:14:07.210441 systemd[1]: dracut-mount.service was skipped because no trigger condition checks were met. Oct 2 19:14:07.211186 systemd[1]: Starting dracut-pre-pivot.service... Oct 2 19:14:07.288065 systemd[1]: Finished dracut-pre-pivot.service. Oct 2 19:14:07.291000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-pivot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.292743 systemd[1]: Starting initrd-cleanup.service... Oct 2 19:14:07.302964 systemd[1]: Stopped target nss-lookup.target. Oct 2 19:14:07.306883 systemd[1]: Stopped target remote-cryptsetup.target. Oct 2 19:14:07.310902 systemd[1]: Stopped target timers.target. Oct 2 19:14:07.314322 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 2 19:14:07.316474 systemd[1]: Stopped dracut-pre-pivot.service. Oct 2 19:14:07.319000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-pivot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.320178 systemd[1]: Stopped target initrd.target. Oct 2 19:14:07.323672 systemd[1]: Stopped target basic.target. Oct 2 19:14:07.326955 systemd[1]: Stopped target ignition-complete.target. Oct 2 19:14:07.330831 systemd[1]: Stopped target ignition-diskful.target. Oct 2 19:14:07.334669 systemd[1]: Stopped target initrd-root-device.target. Oct 2 19:14:07.338568 systemd[1]: Stopped target remote-fs.target. Oct 2 19:14:07.342296 systemd[1]: Stopped target remote-fs-pre.target. Oct 2 19:14:07.346033 systemd[1]: Stopped target sysinit.target. Oct 2 19:14:07.349389 systemd[1]: Stopped target local-fs.target. Oct 2 19:14:07.352685 systemd[1]: Stopped target local-fs-pre.target. Oct 2 19:14:07.356387 systemd[1]: Stopped target swap.target. Oct 2 19:14:07.359521 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 2 19:14:07.361586 systemd[1]: Stopped dracut-pre-mount.service. Oct 2 19:14:07.367000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.367770 systemd[1]: Stopped target cryptsetup.target. Oct 2 19:14:07.371292 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 2 19:14:07.373407 systemd[1]: Stopped dracut-initqueue.service. Oct 2 19:14:07.376000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-initqueue comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.377011 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Oct 2 19:14:07.379551 systemd[1]: Stopped initrd-setup-root-after-ignition.service. Oct 2 19:14:07.383000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root-after-ignition comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.383827 systemd[1]: ignition-files.service: Deactivated successfully. Oct 2 19:14:07.386071 systemd[1]: Stopped ignition-files.service. Oct 2 19:14:07.389000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.389589 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Oct 2 19:14:07.392012 systemd[1]: Stopped flatcar-metadata-hostname.service. Oct 2 19:14:07.396000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=flatcar-metadata-hostname comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.405738 iscsid[823]: iscsid shutting down. Oct 2 19:14:07.405000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.397306 systemd[1]: Stopping ignition-mount.service... Oct 2 19:14:07.410798 ignition[1012]: INFO : Ignition 2.14.0 Oct 2 19:14:07.410798 ignition[1012]: INFO : Stage: umount Oct 2 19:14:07.410798 ignition[1012]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign" Oct 2 19:14:07.410798 ignition[1012]: DEBUG : parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Oct 2 19:14:07.399328 systemd[1]: Stopping iscsid.service... Oct 2 19:14:07.424470 ignition[1012]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 2 19:14:07.401724 systemd[1]: Stopping sysroot-boot.service... Oct 2 19:14:07.403643 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 2 19:14:07.403855 systemd[1]: Stopped systemd-udev-trigger.service. Oct 2 19:14:07.406178 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 2 19:14:07.406324 systemd[1]: Stopped dracut-pre-trigger.service. Oct 2 19:14:07.440000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.442592 systemd[1]: iscsid.service: Deactivated successfully. Oct 2 19:14:07.444688 systemd[1]: Stopped iscsid.service. Oct 2 19:14:07.447000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.449140 systemd[1]: Stopping iscsiuio.service... Oct 2 19:14:07.452617 systemd[1]: iscsiuio.service: Deactivated successfully. Oct 2 19:14:07.454393 ignition[1012]: INFO : Adding "10.200.8.31" to list of CAs Oct 2 19:14:07.456392 ignition[1012]: INFO : umount: umount passed Oct 2 19:14:07.456392 ignition[1012]: INFO : Ignition finished successfully Oct 2 19:14:07.459815 systemd[1]: Stopped iscsiuio.service. Oct 2 19:14:07.462000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsiuio comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.463012 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 2 19:14:07.464945 systemd[1]: Finished initrd-cleanup.service. Oct 2 19:14:07.468000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.468000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.468819 systemd[1]: ignition-mount.service: Deactivated successfully. Oct 2 19:14:07.470828 systemd[1]: Stopped ignition-mount.service. Oct 2 19:14:07.473000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.475054 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 2 19:14:07.475114 systemd[1]: Stopped ignition-disks.service. Oct 2 19:14:07.479000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-disks comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.480409 systemd[1]: ignition-kargs.service: Deactivated successfully. Oct 2 19:14:07.480461 systemd[1]: Stopped ignition-kargs.service. Oct 2 19:14:07.487000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-kargs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.488635 systemd[1]: ignition-fetch.service: Deactivated successfully. Oct 2 19:14:07.488686 systemd[1]: Stopped ignition-fetch.service. Oct 2 19:14:07.493000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.494112 systemd[1]: Stopped target network.target. Oct 2 19:14:07.495981 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Oct 2 19:14:07.496038 systemd[1]: Stopped ignition-fetch-offline.service. Oct 2 19:14:07.503000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch-offline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.503331 systemd[1]: Stopped target paths.target. Oct 2 19:14:07.506492 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 2 19:14:07.509831 systemd[1]: Stopped systemd-ask-password-console.path. Oct 2 19:14:07.513843 systemd[1]: Stopped target slices.target. Oct 2 19:14:07.515496 systemd[1]: Stopped target sockets.target. Oct 2 19:14:07.517319 systemd[1]: iscsid.socket: Deactivated successfully. Oct 2 19:14:07.517370 systemd[1]: Closed iscsid.socket. Oct 2 19:14:07.520442 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 2 19:14:07.530000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.521929 systemd[1]: Closed iscsiuio.socket. Oct 2 19:14:07.525171 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 2 19:14:07.526652 systemd[1]: Stopped ignition-setup.service. Oct 2 19:14:07.531687 systemd[1]: Stopping systemd-networkd.service... Oct 2 19:14:07.537764 systemd[1]: Stopping systemd-resolved.service... Oct 2 19:14:07.543000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=sysroot-boot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.540299 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 2 19:14:07.540378 systemd[1]: Stopped sysroot-boot.service. Oct 2 19:14:07.549000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.543564 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 2 19:14:07.552000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.543604 systemd[1]: Stopped initrd-setup-root.service. Oct 2 19:14:07.543895 systemd-networkd[814]: eth0: DHCPv6 lease lost Oct 2 19:14:07.558000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.549314 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 2 19:14:07.549408 systemd[1]: Stopped systemd-resolved.service. Oct 2 19:14:07.560000 audit: BPF prog-id=6 op=UNLOAD Oct 2 19:14:07.560000 audit: BPF prog-id=9 op=UNLOAD Oct 2 19:14:07.555159 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 2 19:14:07.555251 systemd[1]: Stopped systemd-networkd.service. Oct 2 19:14:07.561031 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 2 19:14:07.561062 systemd[1]: Closed systemd-networkd.socket. Oct 2 19:14:07.571000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=parse-ip-for-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.565019 systemd[1]: Stopping network-cleanup.service... Oct 2 19:14:07.568234 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Oct 2 19:14:07.568295 systemd[1]: Stopped parse-ip-for-networkd.service. Oct 2 19:14:07.572004 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 2 19:14:07.572059 systemd[1]: Stopped systemd-sysctl.service. Oct 2 19:14:07.586000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.586541 systemd[1]: systemd-modules-load.service: Deactivated successfully. Oct 2 19:14:07.586596 systemd[1]: Stopped systemd-modules-load.service. Oct 2 19:14:07.599000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.599607 systemd[1]: Stopping systemd-udevd.service... Oct 2 19:14:07.604950 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 2 19:14:07.607123 systemd[1]: Stopped systemd-udevd.service. Oct 2 19:14:07.610000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.610937 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 2 19:14:07.610989 systemd[1]: Closed systemd-udevd-control.socket. Oct 2 19:14:07.616747 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 2 19:14:07.616790 systemd[1]: Closed systemd-udevd-kernel.socket. Oct 2 19:14:07.622286 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 2 19:14:07.622339 systemd[1]: Stopped dracut-pre-udev.service. Oct 2 19:14:07.627000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.627974 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 2 19:14:07.628024 systemd[1]: Stopped dracut-cmdline.service. Oct 2 19:14:07.633000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.633262 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 2 19:14:07.633311 systemd[1]: Stopped dracut-cmdline-ask.service. Oct 2 19:14:07.638000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.643000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.645000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.639577 systemd[1]: Starting initrd-udevadm-cleanup-db.service... Oct 2 19:14:07.647000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.641512 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 2 19:14:07.641584 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service. Oct 2 19:14:07.643785 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 2 19:14:07.643835 systemd[1]: Stopped kmod-static-nodes.service. Oct 2 19:14:07.645904 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 2 19:14:07.645953 systemd[1]: Stopped systemd-vconsole-setup.service. Oct 2 19:14:07.652368 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 2 19:14:07.664056 systemd[1]: Finished initrd-udevadm-cleanup-db.service. Oct 2 19:14:07.673302 kernel: hv_netvsc 000d3ad9-7de3-000d-3ad9-7de3000d3ad9 eth0: Data path switched from VF: enP3909s1 Oct 2 19:14:07.669000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-udevadm-cleanup-db comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.669000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-udevadm-cleanup-db comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.693998 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 2 19:14:07.697000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=network-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:07.694110 systemd[1]: Stopped network-cleanup.service. Oct 2 19:14:07.698070 systemd[1]: Reached target initrd-switch-root.target. Oct 2 19:14:07.702440 systemd[1]: Starting initrd-switch-root.service... Oct 2 19:14:07.715928 systemd[1]: Switching root. Oct 2 19:14:07.740220 systemd-journald[183]: Journal stopped Oct 2 19:14:21.893094 systemd-journald[183]: Received SIGTERM from PID 1 (systemd). Oct 2 19:14:21.893155 kernel: SELinux: Class mctp_socket not defined in policy. Oct 2 19:14:21.893173 kernel: SELinux: Class anon_inode not defined in policy. Oct 2 19:14:21.893185 kernel: SELinux: the above unknown classes and permissions will be allowed Oct 2 19:14:21.893198 kernel: SELinux: policy capability network_peer_controls=1 Oct 2 19:14:21.893209 kernel: SELinux: policy capability open_perms=1 Oct 2 19:14:21.893221 kernel: SELinux: policy capability extended_socket_class=1 Oct 2 19:14:21.893230 kernel: SELinux: policy capability always_check_network=0 Oct 2 19:14:21.893238 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 2 19:14:21.893246 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 2 19:14:21.893254 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 2 19:14:21.893262 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Oct 2 19:14:21.893269 kernel: kauditd_printk_skb: 43 callbacks suppressed Oct 2 19:14:21.893278 kernel: audit: type=1403 audit(1696274049.984:82): auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 2 19:14:21.893290 systemd[1]: Successfully loaded SELinux policy in 304.975ms. Oct 2 19:14:21.893302 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 45.899ms. Oct 2 19:14:21.893316 systemd[1]: systemd 252 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE -TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Oct 2 19:14:21.893327 systemd[1]: Detected virtualization microsoft. Oct 2 19:14:21.893338 systemd[1]: Detected architecture x86-64. Oct 2 19:14:21.893347 systemd[1]: Detected first boot. Oct 2 19:14:21.893357 systemd[1]: Hostname set to . Oct 2 19:14:21.893366 systemd[1]: Initializing machine ID from random generator. Oct 2 19:14:21.893375 kernel: audit: type=1400 audit(1696274051.099:83): avc: denied { integrity } for pid=1 comm="systemd" lockdown_reason="/dev/mem,kmem,port" scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=lockdown permissive=1 Oct 2 19:14:21.893384 kernel: audit: type=1400 audit(1696274051.115:84): avc: denied { bpf } for pid=1 comm="systemd" capability=39 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Oct 2 19:14:21.893393 kernel: audit: type=1400 audit(1696274051.115:85): avc: denied { perfmon } for pid=1 comm="systemd" capability=38 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Oct 2 19:14:21.893404 kernel: audit: type=1334 audit(1696274051.138:86): prog-id=10 op=LOAD Oct 2 19:14:21.893414 kernel: audit: type=1334 audit(1696274051.138:87): prog-id=10 op=UNLOAD Oct 2 19:14:21.893423 kernel: audit: type=1334 audit(1696274051.143:88): prog-id=11 op=LOAD Oct 2 19:14:21.893431 kernel: audit: type=1334 audit(1696274051.143:89): prog-id=11 op=UNLOAD Oct 2 19:14:21.893442 systemd[1]: Populated /etc with preset unit settings. Oct 2 19:14:21.893452 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Oct 2 19:14:21.893464 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Oct 2 19:14:21.893478 systemd[1]: /run/systemd/system/docker.socket:8: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Oct 2 19:14:21.893488 kernel: audit: type=1334 audit(1696274061.381:90): prog-id=12 op=LOAD Oct 2 19:14:21.893496 kernel: audit: type=1334 audit(1696274061.381:91): prog-id=3 op=UNLOAD Oct 2 19:14:21.893507 kernel: audit: type=1334 audit(1696274061.387:92): prog-id=13 op=LOAD Oct 2 19:14:21.893518 kernel: audit: type=1334 audit(1696274061.391:93): prog-id=14 op=LOAD Oct 2 19:14:21.893527 kernel: audit: type=1334 audit(1696274061.391:94): prog-id=4 op=UNLOAD Oct 2 19:14:21.893537 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 2 19:14:21.893546 kernel: audit: type=1334 audit(1696274061.391:95): prog-id=5 op=UNLOAD Oct 2 19:14:21.893560 kernel: audit: type=1131 audit(1696274061.392:96): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:21.893571 kernel: audit: type=1334 audit(1696274061.423:97): prog-id=12 op=UNLOAD Oct 2 19:14:21.893580 systemd[1]: Stopped initrd-switch-root.service. Oct 2 19:14:21.893592 kernel: audit: type=1130 audit(1696274061.434:98): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:21.893602 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 2 19:14:21.893613 kernel: audit: type=1131 audit(1696274061.434:99): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:21.893623 systemd[1]: Created slice system-addon\x2dconfig.slice. Oct 2 19:14:21.893638 systemd[1]: Created slice system-addon\x2drun.slice. Oct 2 19:14:21.893647 systemd[1]: Created slice system-getty.slice. Oct 2 19:14:21.893659 systemd[1]: Created slice system-modprobe.slice. Oct 2 19:14:21.893669 systemd[1]: Created slice system-serial\x2dgetty.slice. Oct 2 19:14:21.893681 systemd[1]: Created slice system-system\x2dcloudinit.slice. Oct 2 19:14:21.893701 systemd[1]: Created slice system-systemd\x2dfsck.slice. Oct 2 19:14:21.893712 systemd[1]: Created slice user.slice. Oct 2 19:14:21.893724 systemd[1]: Started systemd-ask-password-console.path. Oct 2 19:14:21.893734 systemd[1]: Started systemd-ask-password-wall.path. Oct 2 19:14:21.893748 systemd[1]: Set up automount boot.automount. Oct 2 19:14:21.893760 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount. Oct 2 19:14:21.893770 systemd[1]: Stopped target initrd-switch-root.target. Oct 2 19:14:21.893783 systemd[1]: Stopped target initrd-fs.target. Oct 2 19:14:21.893792 systemd[1]: Stopped target initrd-root-fs.target. Oct 2 19:14:21.893806 systemd[1]: Reached target integritysetup.target. Oct 2 19:14:21.893818 systemd[1]: Reached target remote-cryptsetup.target. Oct 2 19:14:21.893833 systemd[1]: Reached target remote-fs.target. Oct 2 19:14:21.893844 systemd[1]: Reached target slices.target. Oct 2 19:14:21.893855 systemd[1]: Reached target swap.target. Oct 2 19:14:21.893867 systemd[1]: Reached target torcx.target. Oct 2 19:14:21.893879 systemd[1]: Reached target veritysetup.target. Oct 2 19:14:21.893889 systemd[1]: Listening on systemd-coredump.socket. Oct 2 19:14:21.893900 systemd[1]: Listening on systemd-initctl.socket. Oct 2 19:14:21.893911 systemd[1]: Listening on systemd-networkd.socket. Oct 2 19:14:21.893925 systemd[1]: Listening on systemd-udevd-control.socket. Oct 2 19:14:21.893938 systemd[1]: Listening on systemd-udevd-kernel.socket. Oct 2 19:14:21.893947 systemd[1]: Listening on systemd-userdbd.socket. Oct 2 19:14:21.893960 systemd[1]: Mounting dev-hugepages.mount... Oct 2 19:14:21.893970 systemd[1]: Mounting dev-mqueue.mount... Oct 2 19:14:21.893984 systemd[1]: Mounting media.mount... Oct 2 19:14:21.893998 systemd[1]: proc-xen.mount was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 2 19:14:21.894009 systemd[1]: Mounting sys-kernel-debug.mount... Oct 2 19:14:21.894019 systemd[1]: Mounting sys-kernel-tracing.mount... Oct 2 19:14:21.894032 systemd[1]: Mounting tmp.mount... Oct 2 19:14:21.894043 systemd[1]: Starting flatcar-tmpfiles.service... Oct 2 19:14:21.894054 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Oct 2 19:14:21.894066 systemd[1]: Starting kmod-static-nodes.service... Oct 2 19:14:21.894076 systemd[1]: Starting modprobe@configfs.service... Oct 2 19:14:21.894088 systemd[1]: Starting modprobe@dm_mod.service... Oct 2 19:14:21.894100 systemd[1]: Starting modprobe@drm.service... Oct 2 19:14:21.894112 systemd[1]: Starting modprobe@efi_pstore.service... Oct 2 19:14:21.894123 systemd[1]: Starting modprobe@fuse.service... Oct 2 19:14:21.894134 systemd[1]: Starting modprobe@loop.service... Oct 2 19:14:21.894147 systemd[1]: setup-nsswitch.service was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Oct 2 19:14:21.894157 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 2 19:14:21.894169 systemd[1]: Stopped systemd-fsck-root.service. Oct 2 19:14:21.894179 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 2 19:14:21.894191 systemd[1]: Stopped systemd-fsck-usr.service. Oct 2 19:14:21.894206 systemd[1]: Stopped systemd-journald.service. Oct 2 19:14:21.894219 systemd[1]: Starting systemd-journald.service... Oct 2 19:14:21.894232 systemd[1]: Starting systemd-modules-load.service... Oct 2 19:14:21.894243 systemd[1]: Starting systemd-network-generator.service... Oct 2 19:14:21.894256 systemd[1]: Starting systemd-remount-fs.service... Oct 2 19:14:21.894265 systemd[1]: Starting systemd-udev-trigger.service... Oct 2 19:14:21.894278 systemd[1]: verity-setup.service: Deactivated successfully. Oct 2 19:14:21.894289 systemd[1]: Stopped verity-setup.service. Oct 2 19:14:21.894300 systemd[1]: xenserver-pv-version.service was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 2 19:14:21.894315 systemd[1]: Mounted dev-hugepages.mount. Oct 2 19:14:21.894327 systemd[1]: Mounted dev-mqueue.mount. Oct 2 19:14:21.894338 systemd[1]: Mounted media.mount. Oct 2 19:14:21.894349 kernel: fuse: init (API version 7.34) Oct 2 19:14:21.894360 systemd[1]: Mounted sys-kernel-debug.mount. Oct 2 19:14:21.894378 systemd-journald[1130]: Journal started Oct 2 19:14:21.894428 systemd-journald[1130]: Runtime Journal (/run/log/journal/407419266bc849c1a939b9f11ac549dc) is 8.0M, max 159.0M, 151.0M free. Oct 2 19:14:09.984000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 2 19:14:11.099000 audit[1]: AVC avc: denied { integrity } for pid=1 comm="systemd" lockdown_reason="/dev/mem,kmem,port" scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=lockdown permissive=1 Oct 2 19:14:11.115000 audit[1]: AVC avc: denied { bpf } for pid=1 comm="systemd" capability=39 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Oct 2 19:14:11.115000 audit[1]: AVC avc: denied { perfmon } for pid=1 comm="systemd" capability=38 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Oct 2 19:14:11.138000 audit: BPF prog-id=10 op=LOAD Oct 2 19:14:11.138000 audit: BPF prog-id=10 op=UNLOAD Oct 2 19:14:11.143000 audit: BPF prog-id=11 op=LOAD Oct 2 19:14:11.143000 audit: BPF prog-id=11 op=UNLOAD Oct 2 19:14:21.381000 audit: BPF prog-id=12 op=LOAD Oct 2 19:14:21.381000 audit: BPF prog-id=3 op=UNLOAD Oct 2 19:14:21.387000 audit: BPF prog-id=13 op=LOAD Oct 2 19:14:21.391000 audit: BPF prog-id=14 op=LOAD Oct 2 19:14:21.391000 audit: BPF prog-id=4 op=UNLOAD Oct 2 19:14:21.391000 audit: BPF prog-id=5 op=UNLOAD Oct 2 19:14:21.392000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:21.423000 audit: BPF prog-id=12 op=UNLOAD Oct 2 19:14:21.434000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:21.434000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:21.771000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:21.780000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck-usr comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:21.785000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:21.785000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:21.787000 audit: BPF prog-id=15 op=LOAD Oct 2 19:14:21.787000 audit: BPF prog-id=16 op=LOAD Oct 2 19:14:21.787000 audit: BPF prog-id=17 op=LOAD Oct 2 19:14:21.787000 audit: BPF prog-id=13 op=UNLOAD Oct 2 19:14:21.787000 audit: BPF prog-id=14 op=UNLOAD Oct 2 19:14:21.862000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=verity-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:21.889000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Oct 2 19:14:21.889000 audit[1130]: SYSCALL arch=c000003e syscall=46 success=yes exit=60 a0=6 a1=7ffef7c49c30 a2=4000 a3=7ffef7c49ccc items=0 ppid=1 pid=1130 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="systemd-journal" exe="/usr/lib/systemd/systemd-journald" subj=system_u:system_r:kernel_t:s0 key=(null) Oct 2 19:14:21.889000 audit: PROCTITLE proctitle="/usr/lib/systemd/systemd-journald" Oct 2 19:14:21.380369 systemd[1]: Queued start job for default target multi-user.target. Oct 2 19:14:12.393578 /usr/lib/systemd/system-generators/torcx-generator[1045]: time="2023-10-02T19:14:12Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3510.3.0 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.0 /var/lib/torcx/store]" Oct 2 19:14:21.393105 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 2 19:14:12.394028 /usr/lib/systemd/system-generators/torcx-generator[1045]: time="2023-10-02T19:14:12Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 2 19:14:12.394051 /usr/lib/systemd/system-generators/torcx-generator[1045]: time="2023-10-02T19:14:12Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 2 19:14:12.394092 /usr/lib/systemd/system-generators/torcx-generator[1045]: time="2023-10-02T19:14:12Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Oct 2 19:14:12.394104 /usr/lib/systemd/system-generators/torcx-generator[1045]: time="2023-10-02T19:14:12Z" level=debug msg="skipped missing lower profile" missing profile=oem Oct 2 19:14:12.394159 /usr/lib/systemd/system-generators/torcx-generator[1045]: time="2023-10-02T19:14:12Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Oct 2 19:14:12.394175 /usr/lib/systemd/system-generators/torcx-generator[1045]: time="2023-10-02T19:14:12Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Oct 2 19:14:12.394409 /usr/lib/systemd/system-generators/torcx-generator[1045]: time="2023-10-02T19:14:12Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Oct 2 19:14:12.394453 /usr/lib/systemd/system-generators/torcx-generator[1045]: time="2023-10-02T19:14:12Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 2 19:14:12.394470 /usr/lib/systemd/system-generators/torcx-generator[1045]: time="2023-10-02T19:14:12Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 2 19:14:12.394939 /usr/lib/systemd/system-generators/torcx-generator[1045]: time="2023-10-02T19:14:12Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:20.10.torcx.tgz" reference=20.10 Oct 2 19:14:12.394992 /usr/lib/systemd/system-generators/torcx-generator[1045]: time="2023-10-02T19:14:12Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:com.coreos.cl.torcx.tgz" reference=com.coreos.cl Oct 2 19:14:12.395015 /usr/lib/systemd/system-generators/torcx-generator[1045]: time="2023-10-02T19:14:12Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3510.3.0: no such file or directory" path=/usr/share/oem/torcx/store/3510.3.0 Oct 2 19:14:12.395032 /usr/lib/systemd/system-generators/torcx-generator[1045]: time="2023-10-02T19:14:12Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Oct 2 19:14:12.395052 /usr/lib/systemd/system-generators/torcx-generator[1045]: time="2023-10-02T19:14:12Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3510.3.0: no such file or directory" path=/var/lib/torcx/store/3510.3.0 Oct 2 19:14:12.395067 /usr/lib/systemd/system-generators/torcx-generator[1045]: time="2023-10-02T19:14:12Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Oct 2 19:14:20.148360 /usr/lib/systemd/system-generators/torcx-generator[1045]: time="2023-10-02T19:14:20Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 2 19:14:20.148608 /usr/lib/systemd/system-generators/torcx-generator[1045]: time="2023-10-02T19:14:20Z" level=debug msg="binaries propagated" assets="[/bin/containerd /bin/containerd-shim /bin/ctr /bin/docker /bin/docker-containerd /bin/docker-containerd-shim /bin/docker-init /bin/docker-proxy /bin/docker-runc /bin/dockerd /bin/runc /bin/tini]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 2 19:14:20.148739 /usr/lib/systemd/system-generators/torcx-generator[1045]: time="2023-10-02T19:14:20Z" level=debug msg="networkd units propagated" assets="[/lib/systemd/network/50-docker.network /lib/systemd/network/90-docker-veth.network]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 2 19:14:20.148914 /usr/lib/systemd/system-generators/torcx-generator[1045]: time="2023-10-02T19:14:20Z" level=debug msg="systemd units propagated" assets="[/lib/systemd/system/containerd.service /lib/systemd/system/docker.service /lib/systemd/system/docker.socket /lib/systemd/system/sockets.target.wants /lib/systemd/system/multi-user.target.wants]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 2 19:14:20.148962 /usr/lib/systemd/system-generators/torcx-generator[1045]: time="2023-10-02T19:14:20Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Oct 2 19:14:20.149017 /usr/lib/systemd/system-generators/torcx-generator[1045]: time="2023-10-02T19:14:20Z" level=debug msg="system state sealed" content="[TORCX_LOWER_PROFILES=\"vendor\" TORCX_UPPER_PROFILE=\"\" TORCX_PROFILE_PATH=\"/run/torcx/profile.json\" TORCX_BINDIR=\"/run/torcx/bin\" TORCX_UNPACKDIR=\"/run/torcx/unpack\"]" path=/run/metadata/torcx Oct 2 19:14:21.905149 systemd[1]: Started systemd-journald.service. Oct 2 19:14:21.905228 kernel: loop: module loaded Oct 2 19:14:21.907000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:21.908595 systemd[1]: Mounted sys-kernel-tracing.mount. Oct 2 19:14:21.910593 systemd[1]: Mounted tmp.mount. Oct 2 19:14:21.912627 systemd[1]: Finished flatcar-tmpfiles.service. Oct 2 19:14:21.913000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=flatcar-tmpfiles comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:21.914748 systemd[1]: Finished kmod-static-nodes.service. Oct 2 19:14:21.915000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:21.916905 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 2 19:14:21.917046 systemd[1]: Finished modprobe@configfs.service. Oct 2 19:14:21.918000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@configfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:21.918000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@configfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:21.919098 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 2 19:14:21.919235 systemd[1]: Finished modprobe@dm_mod.service. Oct 2 19:14:21.920000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:21.920000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:21.921272 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 2 19:14:21.921410 systemd[1]: Finished modprobe@drm.service. Oct 2 19:14:21.922000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:21.922000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:21.924000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:21.925000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:21.923572 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 2 19:14:21.923748 systemd[1]: Finished modprobe@efi_pstore.service. Oct 2 19:14:21.925995 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 2 19:14:21.926128 systemd[1]: Finished modprobe@fuse.service. Oct 2 19:14:21.929000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@fuse comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:21.929000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@fuse comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:21.930046 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 2 19:14:21.930188 systemd[1]: Finished modprobe@loop.service. Oct 2 19:14:21.931000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:21.931000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:21.932440 systemd[1]: Finished systemd-network-generator.service. Oct 2 19:14:21.933000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-network-generator comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:21.934629 systemd[1]: Finished systemd-remount-fs.service. Oct 2 19:14:21.935000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-remount-fs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:21.937053 systemd[1]: Reached target network-pre.target. Oct 2 19:14:21.940228 systemd[1]: Mounting sys-fs-fuse-connections.mount... Oct 2 19:14:21.943760 systemd[1]: Mounting sys-kernel-config.mount... Oct 2 19:14:21.948717 systemd[1]: remount-root.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Oct 2 19:14:21.964607 systemd[1]: Starting systemd-hwdb-update.service... Oct 2 19:14:21.968491 systemd[1]: Starting systemd-journal-flush.service... Oct 2 19:14:21.970644 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 2 19:14:21.972282 systemd[1]: Starting systemd-random-seed.service... Oct 2 19:14:21.974960 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Oct 2 19:14:21.976783 systemd[1]: Starting systemd-sysusers.service... Oct 2 19:14:21.983388 systemd[1]: Finished systemd-modules-load.service. Oct 2 19:14:21.985000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:21.986469 systemd[1]: Mounted sys-fs-fuse-connections.mount. Oct 2 19:14:21.988921 systemd[1]: Mounted sys-kernel-config.mount. Oct 2 19:14:21.992667 systemd[1]: Starting systemd-sysctl.service... Oct 2 19:14:22.037123 systemd-journald[1130]: Time spent on flushing to /var/log/journal/407419266bc849c1a939b9f11ac549dc is 22.767ms for 1121 entries. Oct 2 19:14:22.037123 systemd-journald[1130]: System Journal (/var/log/journal/407419266bc849c1a939b9f11ac549dc) is 8.0M, max 2.6G, 2.6G free. Oct 2 19:14:22.203486 systemd-journald[1130]: Received client request to flush runtime journal. Oct 2 19:14:22.032000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:22.044000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-random-seed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:22.081000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:22.030344 systemd[1]: Finished systemd-udev-trigger.service. Oct 2 19:14:22.034670 systemd[1]: Starting systemd-udev-settle.service... Oct 2 19:14:22.204186 udevadm[1168]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Oct 2 19:14:22.042819 systemd[1]: Finished systemd-random-seed.service. Oct 2 19:14:22.045212 systemd[1]: Reached target first-boot-complete.target. Oct 2 19:14:22.079797 systemd[1]: Finished systemd-sysctl.service. Oct 2 19:14:22.204734 systemd[1]: Finished systemd-journal-flush.service. Oct 2 19:14:22.206000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journal-flush comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:22.658000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-sysusers comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:22.656719 systemd[1]: Finished systemd-sysusers.service. Oct 2 19:14:22.660576 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... Oct 2 19:14:23.125530 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. Oct 2 19:14:23.127000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:23.321715 systemd[1]: Finished systemd-hwdb-update.service. Oct 2 19:14:23.323000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-hwdb-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:23.323000 audit: BPF prog-id=18 op=LOAD Oct 2 19:14:23.323000 audit: BPF prog-id=19 op=LOAD Oct 2 19:14:23.323000 audit: BPF prog-id=7 op=UNLOAD Oct 2 19:14:23.323000 audit: BPF prog-id=8 op=UNLOAD Oct 2 19:14:23.325387 systemd[1]: Starting systemd-udevd.service... Oct 2 19:14:23.343467 systemd-udevd[1173]: Using default interface naming scheme 'v252'. Oct 2 19:14:23.578000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:23.580000 audit: BPF prog-id=20 op=LOAD Oct 2 19:14:23.576739 systemd[1]: Started systemd-udevd.service. Oct 2 19:14:23.581686 systemd[1]: Starting systemd-networkd.service... Oct 2 19:14:23.618569 systemd[1]: Condition check resulted in dev-ttyS0.device being skipped. Oct 2 19:14:23.680000 audit[1179]: AVC avc: denied { confidentiality } for pid=1179 comm="(udev-worker)" 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 Oct 2 19:14:23.688713 kernel: hv_vmbus: registering driver hv_balloon Oct 2 19:14:23.707217 kernel: mousedev: PS/2 mouse device common for all mice Oct 2 19:14:23.704000 audit: BPF prog-id=21 op=LOAD Oct 2 19:14:23.704000 audit: BPF prog-id=22 op=LOAD Oct 2 19:14:23.704000 audit: BPF prog-id=23 op=LOAD Oct 2 19:14:23.705460 systemd[1]: Starting systemd-userdbd.service... Oct 2 19:14:23.718773 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Oct 2 19:14:23.730311 kernel: hv_utils: Registering HyperV Utility Driver Oct 2 19:14:23.730389 kernel: hv_vmbus: registering driver hv_utils Oct 2 19:14:23.680000 audit[1179]: SYSCALL arch=c000003e syscall=175 success=yes exit=0 a0=55a19453afe0 a1=f884 a2=7faecf779bc5 a3=5 items=10 ppid=1173 pid=1179 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="(udev-worker)" exe="/usr/bin/udevadm" subj=system_u:system_r:kernel_t:s0 key=(null) Oct 2 19:14:23.680000 audit: CWD cwd="/" Oct 2 19:14:23.680000 audit: PATH item=0 name=(null) inode=15527 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Oct 2 19:14:23.680000 audit: PATH item=1 name=(null) inode=15528 dev=00:0b mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Oct 2 19:14:23.680000 audit: PATH item=2 name=(null) inode=15527 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Oct 2 19:14:23.680000 audit: PATH item=3 name=(null) inode=15529 dev=00:0b mode=0100440 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Oct 2 19:14:23.680000 audit: PATH item=4 name=(null) inode=15527 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Oct 2 19:14:23.680000 audit: PATH item=5 name=(null) inode=15530 dev=00:0b mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Oct 2 19:14:23.680000 audit: PATH item=6 name=(null) inode=15527 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Oct 2 19:14:23.680000 audit: PATH item=7 name=(null) inode=15531 dev=00:0b mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Oct 2 19:14:23.680000 audit: PATH item=8 name=(null) inode=15527 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Oct 2 19:14:23.680000 audit: PATH item=9 name=(null) inode=15532 dev=00:0b mode=0100440 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Oct 2 19:14:23.680000 audit: PROCTITLE proctitle="(udev-worker)" Oct 2 19:14:23.746633 kernel: hv_utils: Heartbeat IC version 3.0 Oct 2 19:14:23.746724 kernel: hv_utils: Shutdown IC version 3.2 Oct 2 19:14:23.746757 kernel: hv_utils: TimeSync IC version 4.0 Oct 2 19:14:24.405924 kernel: hv_vmbus: registering driver hyperv_fb Oct 2 19:14:24.404000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-userdbd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:24.393280 systemd[1]: Started systemd-userdbd.service. Oct 2 19:14:24.437976 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Oct 2 19:14:24.443961 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Oct 2 19:14:24.449399 kernel: Console: switching to colour dummy device 80x25 Oct 2 19:14:24.457116 kernel: Console: switching to colour frame buffer device 128x48 Oct 2 19:14:24.632963 kernel: BTRFS info: devid 1 device path /dev/disk/by-label/OEM changed to /dev/sda6 scanned by (udev-worker) (1184) Oct 2 19:14:24.678964 kernel: KVM: vmx: using Hyper-V Enlightened VMCS Oct 2 19:14:24.704833 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Oct 2 19:14:24.730398 systemd[1]: Finished systemd-udev-settle.service. Oct 2 19:14:24.731000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udev-settle comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:24.734187 systemd[1]: Starting lvm2-activation-early.service... Oct 2 19:14:24.780614 systemd-networkd[1186]: lo: Link UP Oct 2 19:14:24.780625 systemd-networkd[1186]: lo: Gained carrier Oct 2 19:14:24.781236 systemd-networkd[1186]: Enumeration completed Oct 2 19:14:24.781358 systemd[1]: Started systemd-networkd.service. Oct 2 19:14:24.782000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:24.785078 systemd[1]: Starting systemd-networkd-wait-online.service... Oct 2 19:14:24.808792 systemd-networkd[1186]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 2 19:14:24.861972 kernel: mlx5_core 0f45:00:02.0 enP3909s1: Link up Oct 2 19:14:24.898968 kernel: hv_netvsc 000d3ad9-7de3-000d-3ad9-7de3000d3ad9 eth0: Data path switched to VF: enP3909s1 Oct 2 19:14:24.900107 systemd-networkd[1186]: enP3909s1: Link UP Oct 2 19:14:24.900454 systemd-networkd[1186]: eth0: Link UP Oct 2 19:14:24.900578 systemd-networkd[1186]: eth0: Gained carrier Oct 2 19:14:24.906614 systemd-networkd[1186]: enP3909s1: Gained carrier Oct 2 19:14:24.941079 systemd-networkd[1186]: eth0: DHCPv4 address 10.200.8.42/24, gateway 10.200.8.1 acquired from 168.63.129.16 Oct 2 19:14:25.047813 lvm[1249]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 2 19:14:25.079121 systemd[1]: Finished lvm2-activation-early.service. Oct 2 19:14:25.080000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=lvm2-activation-early comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:25.081554 systemd[1]: Reached target cryptsetup.target. Oct 2 19:14:25.084647 systemd[1]: Starting lvm2-activation.service... Oct 2 19:14:25.089344 lvm[1252]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 2 19:14:25.118206 systemd[1]: Finished lvm2-activation.service. Oct 2 19:14:25.119000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=lvm2-activation comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:25.121079 systemd[1]: Reached target local-fs-pre.target. Oct 2 19:14:25.123564 systemd[1]: var-lib-machines.mount was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Oct 2 19:14:25.123610 systemd[1]: Reached target local-fs.target. Oct 2 19:14:25.126087 systemd[1]: Reached target machines.target. Oct 2 19:14:25.129552 systemd[1]: Starting ldconfig.service... Oct 2 19:14:25.144649 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Oct 2 19:14:25.144728 systemd[1]: systemd-boot-system-token.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/LoaderFeatures-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Oct 2 19:14:25.145978 systemd[1]: Starting systemd-boot-update.service... Oct 2 19:14:25.149102 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service... Oct 2 19:14:25.153021 systemd[1]: Starting systemd-machine-id-commit.service... Oct 2 19:14:25.155250 systemd[1]: systemd-sysext.service was skipped because no trigger condition checks were met. Oct 2 19:14:25.155334 systemd[1]: ensure-sysext.service was skipped because no trigger condition checks were met. Oct 2 19:14:25.156437 systemd[1]: Starting systemd-tmpfiles-setup.service... Oct 2 19:14:25.183837 systemd-tmpfiles[1257]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Oct 2 19:14:25.189000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck@dev-disk-by\x2dlabel-OEM comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:25.188066 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service. Oct 2 19:14:25.207818 systemd-tmpfiles[1257]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Oct 2 19:14:25.219760 systemd[1]: boot.automount: Got automount request for /boot, triggered by 1254 (bootctl) Oct 2 19:14:25.220974 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service... Oct 2 19:14:25.234304 systemd-tmpfiles[1257]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Oct 2 19:14:25.286421 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 2 19:14:25.287135 systemd[1]: Finished systemd-machine-id-commit.service. Oct 2 19:14:25.287000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-machine-id-commit comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:25.935351 systemd-fsck[1262]: fsck.fat 4.2 (2021-01-31) Oct 2 19:14:25.935351 systemd-fsck[1262]: /dev/sda1: 789 files, 115069/258078 clusters Oct 2 19:14:25.937702 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service. Oct 2 19:14:25.940000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:25.942686 systemd[1]: Mounting boot.mount... Oct 2 19:14:25.954962 systemd[1]: Mounted boot.mount. Oct 2 19:14:25.968055 systemd[1]: Finished systemd-boot-update.service. Oct 2 19:14:25.970000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-boot-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:26.001087 systemd-networkd[1186]: eth0: Gained IPv6LL Oct 2 19:14:26.005751 systemd[1]: Finished systemd-networkd-wait-online.service. Oct 2 19:14:26.007000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-networkd-wait-online comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:26.759354 systemd[1]: Finished systemd-tmpfiles-setup.service. Oct 2 19:14:26.761000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:26.763395 systemd[1]: Starting audit-rules.service... Oct 2 19:14:26.766736 systemd[1]: Starting clean-ca-certificates.service... Oct 2 19:14:26.770151 systemd[1]: Starting systemd-journal-catalog-update.service... Oct 2 19:14:26.772000 audit: BPF prog-id=24 op=LOAD Oct 2 19:14:26.774711 systemd[1]: Starting systemd-resolved.service... Oct 2 19:14:26.778000 audit: BPF prog-id=25 op=LOAD Oct 2 19:14:26.780385 systemd[1]: Starting systemd-timesyncd.service... Oct 2 19:14:26.786556 systemd[1]: Starting systemd-update-utmp.service... Oct 2 19:14:26.808000 audit[1273]: SYSTEM_BOOT pid=1273 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg=' comm="systemd-update-utmp" exe="/usr/lib/systemd/systemd-update-utmp" hostname=? addr=? terminal=? res=success' Oct 2 19:14:26.813503 systemd[1]: Finished systemd-update-utmp.service. Oct 2 19:14:26.815000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-update-utmp comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:26.857062 systemd[1]: Finished clean-ca-certificates.service. Oct 2 19:14:26.858000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=clean-ca-certificates comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:26.859529 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Oct 2 19:14:26.861402 systemd[1]: Finished systemd-journal-catalog-update.service. Oct 2 19:14:26.862000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journal-catalog-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:26.941914 systemd[1]: Started systemd-timesyncd.service. Oct 2 19:14:26.943000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-timesyncd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:14:26.944472 systemd[1]: Reached target time-set.target. Oct 2 19:14:26.968162 systemd-resolved[1271]: Positive Trust Anchors: Oct 2 19:14:26.968176 systemd-resolved[1271]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 2 19:14:26.968214 systemd-resolved[1271]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Oct 2 19:14:27.043000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Oct 2 19:14:27.045161 augenrules[1289]: No rules Oct 2 19:14:27.048962 kernel: kauditd_printk_skb: 79 callbacks suppressed Oct 2 19:14:27.049053 kernel: audit: type=1305 audit(1696274067.043:164): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Oct 2 19:14:27.052908 systemd[1]: Finished audit-rules.service. Oct 2 19:14:27.066899 kernel: audit: type=1300 audit(1696274067.043:164): arch=c000003e syscall=44 success=yes exit=1056 a0=3 a1=7ffdbeea5ef0 a2=420 a3=0 items=0 ppid=1268 pid=1289 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditctl" exe="/usr/sbin/auditctl" subj=system_u:system_r:kernel_t:s0 key=(null) Oct 2 19:14:27.043000 audit[1289]: SYSCALL arch=c000003e syscall=44 success=yes exit=1056 a0=3 a1=7ffdbeea5ef0 a2=420 a3=0 items=0 ppid=1268 pid=1289 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditctl" exe="/usr/sbin/auditctl" subj=system_u:system_r:kernel_t:s0 key=(null) Oct 2 19:14:27.043000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573 Oct 2 19:14:27.073960 kernel: audit: type=1327 audit(1696274067.043:164): proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573 Oct 2 19:14:27.118156 systemd-resolved[1271]: Using system hostname 'ci-3510.3.0-a-aff9975401'. Oct 2 19:14:27.119762 systemd[1]: Started systemd-resolved.service. Oct 2 19:14:27.122392 systemd[1]: Reached target network.target. Oct 2 19:14:27.124290 systemd[1]: Reached target network-online.target. Oct 2 19:14:27.126496 systemd[1]: Reached target nss-lookup.target. Oct 2 19:14:27.128712 systemd-timesyncd[1272]: Contacted time server 188.125.64.7:123 (0.flatcar.pool.ntp.org). Oct 2 19:14:27.128783 systemd-timesyncd[1272]: Initial clock synchronization to Mon 2023-10-02 19:14:27.130140 UTC. Oct 2 19:14:31.826540 ldconfig[1253]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 2 19:14:31.839198 systemd[1]: Finished ldconfig.service. Oct 2 19:14:31.842762 systemd[1]: Starting systemd-update-done.service... Oct 2 19:14:31.864950 systemd[1]: Finished systemd-update-done.service. Oct 2 19:14:31.867289 systemd[1]: Reached target sysinit.target. Oct 2 19:14:31.869091 systemd[1]: Started motdgen.path. Oct 2 19:14:31.870645 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path. Oct 2 19:14:31.873409 systemd[1]: Started logrotate.timer. Oct 2 19:14:31.875104 systemd[1]: Started mdadm.timer. Oct 2 19:14:31.876531 systemd[1]: Started systemd-tmpfiles-clean.timer. Oct 2 19:14:31.878360 systemd[1]: update-engine-stub.timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Oct 2 19:14:31.878398 systemd[1]: Reached target paths.target. Oct 2 19:14:31.880055 systemd[1]: Reached target timers.target. Oct 2 19:14:31.882268 systemd[1]: Listening on dbus.socket. Oct 2 19:14:31.884866 systemd[1]: Starting docker.socket... Oct 2 19:14:31.906857 systemd[1]: Listening on sshd.socket. Oct 2 19:14:31.908910 systemd[1]: systemd-pcrphase-sysinit.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Oct 2 19:14:31.909471 systemd[1]: Listening on docker.socket. Oct 2 19:14:31.911200 systemd[1]: Reached target sockets.target. Oct 2 19:14:31.913004 systemd[1]: Reached target basic.target. Oct 2 19:14:31.914648 systemd[1]: addon-config@usr-share-oem.service was skipped because no trigger condition checks were met. Oct 2 19:14:31.914683 systemd[1]: addon-run@usr-share-oem.service was skipped because no trigger condition checks were met. Oct 2 19:14:31.915735 systemd[1]: Starting containerd.service... Oct 2 19:14:31.918552 systemd[1]: Starting dbus.service... Oct 2 19:14:31.921256 systemd[1]: Starting enable-oem-cloudinit.service... Oct 2 19:14:31.924153 systemd[1]: Starting extend-filesystems.service... Oct 2 19:14:31.925897 systemd[1]: flatcar-setup-environment.service was skipped because of an unmet condition check (ConditionPathExists=/usr/share/oem/bin/flatcar-setup-environment). Oct 2 19:14:31.927329 systemd[1]: Starting motdgen.service... Oct 2 19:14:31.933918 systemd[1]: Started nvidia.service. Oct 2 19:14:31.936972 systemd[1]: Starting ssh-key-proc-cmdline.service... Oct 2 19:14:31.940704 systemd[1]: Starting sshd-keygen.service... Oct 2 19:14:31.946299 systemd[1]: Starting systemd-logind.service... Oct 2 19:14:31.948142 systemd[1]: systemd-pcrphase.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Oct 2 19:14:31.948233 systemd[1]: tcsd.service was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Oct 2 19:14:31.948800 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 2 19:14:31.950217 systemd[1]: Starting update-engine.service... Oct 2 19:14:31.953796 systemd[1]: Starting update-ssh-keys-after-ignition.service... Oct 2 19:14:31.958477 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 2 19:14:31.958689 systemd[1]: Finished ssh-key-proc-cmdline.service. Oct 2 19:14:32.034172 extend-filesystems[1300]: Found sda Oct 2 19:14:32.036464 extend-filesystems[1300]: Found sda1 Oct 2 19:14:32.036464 extend-filesystems[1300]: Found sda2 Oct 2 19:14:32.036464 extend-filesystems[1300]: Found sda3 Oct 2 19:14:32.036464 extend-filesystems[1300]: Found usr Oct 2 19:14:32.036464 extend-filesystems[1300]: Found sda4 Oct 2 19:14:32.036464 extend-filesystems[1300]: Found sda6 Oct 2 19:14:32.036464 extend-filesystems[1300]: Found sda7 Oct 2 19:14:32.036464 extend-filesystems[1300]: Found sda9 Oct 2 19:14:32.036464 extend-filesystems[1300]: Checking size of /dev/sda9 Oct 2 19:14:32.048989 systemd[1]: motdgen.service: Deactivated successfully. Oct 2 19:14:32.049157 systemd[1]: Finished motdgen.service. Oct 2 19:14:32.080203 systemd-logind[1308]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Oct 2 19:14:32.088550 env[1321]: time="2023-10-02T19:14:32.088499409Z" level=info msg="starting containerd" revision=92b3a9d6f1b3bcc6dc74875cfdea653fe39f09c2 version=1.6.16 Oct 2 19:14:32.090689 systemd-logind[1308]: New seat seat0. Oct 2 19:14:32.099988 jq[1299]: false Oct 2 19:14:32.102615 jq[1312]: true Oct 2 19:14:32.103742 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Oct 2 19:14:32.103928 systemd[1]: Condition check resulted in enable-oem-cloudinit.service being skipped. Oct 2 19:14:32.111018 jq[1344]: true Oct 2 19:14:32.118123 extend-filesystems[1300]: Old size kept for /dev/sda9 Oct 2 19:14:32.121959 extend-filesystems[1300]: Found sr0 Oct 2 19:14:32.125807 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 2 19:14:32.126038 systemd[1]: Finished extend-filesystems.service. Oct 2 19:14:32.182032 env[1321]: time="2023-10-02T19:14:32.180860088Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Oct 2 19:14:32.182032 env[1321]: time="2023-10-02T19:14:32.181090505Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Oct 2 19:14:32.186448 env[1321]: time="2023-10-02T19:14:32.185339117Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/5.15.132-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Oct 2 19:14:32.186448 env[1321]: time="2023-10-02T19:14:32.185377820Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Oct 2 19:14:32.186448 env[1321]: time="2023-10-02T19:14:32.185635339Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Oct 2 19:14:32.186448 env[1321]: time="2023-10-02T19:14:32.185657741Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Oct 2 19:14:32.186448 env[1321]: time="2023-10-02T19:14:32.185675042Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" Oct 2 19:14:32.186448 env[1321]: time="2023-10-02T19:14:32.185690143Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Oct 2 19:14:32.186448 env[1321]: time="2023-10-02T19:14:32.185774349Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Oct 2 19:14:32.186448 env[1321]: time="2023-10-02T19:14:32.186059970Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Oct 2 19:14:32.186448 env[1321]: time="2023-10-02T19:14:32.186219982Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Oct 2 19:14:32.186448 env[1321]: time="2023-10-02T19:14:32.186240783Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Oct 2 19:14:32.186868 env[1321]: time="2023-10-02T19:14:32.186295887Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" Oct 2 19:14:32.186868 env[1321]: time="2023-10-02T19:14:32.186311189Z" level=info msg="metadata content store policy set" policy=shared Oct 2 19:14:32.244849 env[1321]: time="2023-10-02T19:14:32.244798181Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Oct 2 19:14:32.245033 env[1321]: time="2023-10-02T19:14:32.244855486Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Oct 2 19:14:32.245033 env[1321]: time="2023-10-02T19:14:32.244872287Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Oct 2 19:14:32.245033 env[1321]: time="2023-10-02T19:14:32.244919390Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Oct 2 19:14:32.245033 env[1321]: time="2023-10-02T19:14:32.244953093Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Oct 2 19:14:32.245033 env[1321]: time="2023-10-02T19:14:32.244973194Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Oct 2 19:14:32.245033 env[1321]: time="2023-10-02T19:14:32.244989395Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Oct 2 19:14:32.245033 env[1321]: time="2023-10-02T19:14:32.245018598Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Oct 2 19:14:32.245289 env[1321]: time="2023-10-02T19:14:32.245036099Z" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1 Oct 2 19:14:32.245289 env[1321]: time="2023-10-02T19:14:32.245055400Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Oct 2 19:14:32.245289 env[1321]: time="2023-10-02T19:14:32.245071701Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Oct 2 19:14:32.245289 env[1321]: time="2023-10-02T19:14:32.245090003Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Oct 2 19:14:32.245289 env[1321]: time="2023-10-02T19:14:32.245234313Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Oct 2 19:14:32.245459 env[1321]: time="2023-10-02T19:14:32.245331621Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Oct 2 19:14:32.245659 env[1321]: time="2023-10-02T19:14:32.245629942Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Oct 2 19:14:32.245806 env[1321]: time="2023-10-02T19:14:32.245676246Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Oct 2 19:14:32.245806 env[1321]: time="2023-10-02T19:14:32.245694547Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Oct 2 19:14:32.245806 env[1321]: time="2023-10-02T19:14:32.245749951Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Oct 2 19:14:32.245806 env[1321]: time="2023-10-02T19:14:32.245766352Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Oct 2 19:14:32.245806 env[1321]: time="2023-10-02T19:14:32.245784654Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Oct 2 19:14:32.245806 env[1321]: time="2023-10-02T19:14:32.245799155Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Oct 2 19:14:32.246174 env[1321]: time="2023-10-02T19:14:32.245816056Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Oct 2 19:14:32.246174 env[1321]: time="2023-10-02T19:14:32.245833957Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Oct 2 19:14:32.246174 env[1321]: time="2023-10-02T19:14:32.245852159Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Oct 2 19:14:32.246174 env[1321]: time="2023-10-02T19:14:32.245868660Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Oct 2 19:14:32.246174 env[1321]: time="2023-10-02T19:14:32.245894662Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Oct 2 19:14:32.246174 env[1321]: time="2023-10-02T19:14:32.246081876Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Oct 2 19:14:32.246174 env[1321]: time="2023-10-02T19:14:32.246107178Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Oct 2 19:14:32.246174 env[1321]: time="2023-10-02T19:14:32.246124679Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Oct 2 19:14:32.246174 env[1321]: time="2023-10-02T19:14:32.246143580Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Oct 2 19:14:32.246174 env[1321]: time="2023-10-02T19:14:32.246164582Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1 Oct 2 19:14:32.246527 env[1321]: time="2023-10-02T19:14:32.246181183Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Oct 2 19:14:32.246527 env[1321]: time="2023-10-02T19:14:32.246204885Z" level=error msg="failed to initialize a tracing processor \"otlp\"" error="no OpenTelemetry endpoint: skip plugin" Oct 2 19:14:32.246527 env[1321]: time="2023-10-02T19:14:32.246245188Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Oct 2 19:14:32.246633 env[1321]: time="2023-10-02T19:14:32.246545410Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.6 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Oct 2 19:14:32.246633 env[1321]: time="2023-10-02T19:14:32.246621515Z" level=info msg="Connect containerd service" Oct 2 19:14:32.288140 env[1321]: time="2023-10-02T19:14:32.246662918Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Oct 2 19:14:32.288140 env[1321]: time="2023-10-02T19:14:32.250576006Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Oct 2 19:14:32.288140 env[1321]: time="2023-10-02T19:14:32.250893829Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Oct 2 19:14:32.288140 env[1321]: time="2023-10-02T19:14:32.250967934Z" level=info msg=serving... address=/run/containerd/containerd.sock Oct 2 19:14:32.288140 env[1321]: time="2023-10-02T19:14:32.251022838Z" level=info msg="containerd successfully booted in 0.163376s" Oct 2 19:14:32.288140 env[1321]: time="2023-10-02T19:14:32.271758760Z" level=info msg="Start subscribing containerd event" Oct 2 19:14:32.288140 env[1321]: time="2023-10-02T19:14:32.271834366Z" level=info msg="Start recovering state" Oct 2 19:14:32.288140 env[1321]: time="2023-10-02T19:14:32.271903571Z" level=info msg="Start event monitor" Oct 2 19:14:32.288140 env[1321]: time="2023-10-02T19:14:32.271925373Z" level=info msg="Start snapshots syncer" Oct 2 19:14:32.288140 env[1321]: time="2023-10-02T19:14:32.271951074Z" level=info msg="Start cni network conf syncer for default" Oct 2 19:14:32.288140 env[1321]: time="2023-10-02T19:14:32.271963375Z" level=info msg="Start streaming server" Oct 2 19:14:32.251081 systemd[1]: Started containerd.service. Oct 2 19:14:32.324671 dbus-daemon[1298]: [system] SELinux support is enabled Oct 2 19:14:32.324856 systemd[1]: Started dbus.service. Oct 2 19:14:32.329256 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Oct 2 19:14:32.329290 systemd[1]: Reached target system-config.target. Oct 2 19:14:32.332148 systemd[1]: user-cloudinit-proc-cmdline.service was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Oct 2 19:14:32.332178 systemd[1]: Reached target user-config.target. Oct 2 19:14:32.335830 systemd[1]: Started systemd-logind.service. Oct 2 19:14:32.337417 dbus-daemon[1298]: [system] Successfully activated service 'org.freedesktop.systemd1' Oct 2 19:14:32.362987 systemd[1]: nvidia.service: Deactivated successfully. Oct 2 19:14:32.402697 bash[1363]: Updated "/home/core/.ssh/authorized_keys" Oct 2 19:14:32.403355 systemd[1]: Finished update-ssh-keys-after-ignition.service. Oct 2 19:14:32.918963 update_engine[1310]: I1002 19:14:32.918249 1310 main.cc:92] Flatcar Update Engine starting Oct 2 19:14:32.967375 systemd[1]: Started update-engine.service. Oct 2 19:14:32.970086 update_engine[1310]: I1002 19:14:32.967450 1310 update_check_scheduler.cc:74] Next update check in 2m50s Oct 2 19:14:32.972257 systemd[1]: Started locksmithd.service. Oct 2 19:14:33.531633 sshd_keygen[1317]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Oct 2 19:14:33.552160 systemd[1]: Finished sshd-keygen.service. Oct 2 19:14:33.556097 systemd[1]: Starting issuegen.service... Oct 2 19:14:33.559911 systemd[1]: Started waagent.service. Oct 2 19:14:33.566285 systemd[1]: issuegen.service: Deactivated successfully. Oct 2 19:14:33.566441 systemd[1]: Finished issuegen.service. Oct 2 19:14:33.569995 systemd[1]: Starting systemd-user-sessions.service... Oct 2 19:14:33.597028 systemd[1]: Finished systemd-user-sessions.service. Oct 2 19:14:33.601540 systemd[1]: Started getty@tty1.service. Oct 2 19:14:33.605485 systemd[1]: Started serial-getty@ttyS0.service. Oct 2 19:14:33.608181 systemd[1]: Reached target getty.target. Oct 2 19:14:33.609904 systemd[1]: Reached target multi-user.target. Oct 2 19:14:33.613282 systemd[1]: Starting systemd-update-utmp-runlevel.service... Oct 2 19:14:33.622327 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 2 19:14:33.622618 systemd[1]: Finished systemd-update-utmp-runlevel.service. Oct 2 19:14:33.625164 systemd[1]: Startup finished in 845ms (firmware) + 27.813s (loader) + 903ms (kernel) + 11.633s (initrd) + 23.640s (userspace) = 1min 4.837s. Oct 2 19:14:34.023413 login[1413]: pam_lastlog(login:session): file /var/log/lastlog is locked/write Oct 2 19:14:34.024096 login[1412]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Oct 2 19:14:34.048523 systemd[1]: Created slice user-500.slice. Oct 2 19:14:34.050013 systemd[1]: Starting user-runtime-dir@500.service... Oct 2 19:14:34.053003 systemd-logind[1308]: New session 2 of user core. Oct 2 19:14:34.082536 systemd[1]: Finished user-runtime-dir@500.service. Oct 2 19:14:34.084352 systemd[1]: Starting user@500.service... Oct 2 19:14:34.088989 (systemd)[1416]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 2 19:14:34.254588 systemd[1416]: Queued start job for default target default.target. Oct 2 19:14:34.255398 systemd[1416]: Reached target paths.target. Oct 2 19:14:34.255434 systemd[1416]: Reached target sockets.target. Oct 2 19:14:34.255455 systemd[1416]: Reached target timers.target. Oct 2 19:14:34.255474 systemd[1416]: Reached target basic.target. Oct 2 19:14:34.255545 systemd[1416]: Reached target default.target. Oct 2 19:14:34.255592 systemd[1416]: Startup finished in 160ms. Oct 2 19:14:34.255632 systemd[1]: Started user@500.service. Oct 2 19:14:34.257261 systemd[1]: Started session-2.scope. Oct 2 19:14:34.343073 locksmithd[1394]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 2 19:14:35.025516 login[1413]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Oct 2 19:14:35.029909 systemd-logind[1308]: New session 1 of user core. Oct 2 19:14:35.031105 systemd[1]: Started session-1.scope. Oct 2 19:14:40.338709 waagent[1407]: 2023-10-02T19:14:40.338608Z INFO Daemon Daemon Azure Linux Agent Version:2.6.0.2 Oct 2 19:14:40.340420 waagent[1407]: 2023-10-02T19:14:40.340352Z INFO Daemon Daemon OS: flatcar 3510.3.0 Oct 2 19:14:40.341711 waagent[1407]: 2023-10-02T19:14:40.341659Z INFO Daemon Daemon Python: 3.9.16 Oct 2 19:14:40.342727 waagent[1407]: 2023-10-02T19:14:40.342671Z INFO Daemon Daemon Run daemon Oct 2 19:14:40.343973 waagent[1407]: 2023-10-02T19:14:40.343912Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='3510.3.0' Oct 2 19:14:40.358289 waagent[1407]: 2023-10-02T19:14:40.358163Z INFO Daemon Daemon Unable to get cloud-init enabled status from systemctl: Command '['systemctl', 'is-enabled', 'cloud-init-local.service']' returned non-zero exit status 1. Oct 2 19:14:40.366260 waagent[1407]: 2023-10-02T19:14:40.366138Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Oct 2 19:14:40.370923 waagent[1407]: 2023-10-02T19:14:40.370851Z INFO Daemon Daemon cloud-init is enabled: False Oct 2 19:14:40.373439 waagent[1407]: 2023-10-02T19:14:40.373374Z INFO Daemon Daemon Using waagent for provisioning Oct 2 19:14:40.376463 waagent[1407]: 2023-10-02T19:14:40.376403Z INFO Daemon Daemon Activate resource disk Oct 2 19:14:40.378825 waagent[1407]: 2023-10-02T19:14:40.378765Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Oct 2 19:14:40.388884 waagent[1407]: 2023-10-02T19:14:40.388807Z INFO Daemon Daemon Found device: None Oct 2 19:14:40.391490 waagent[1407]: 2023-10-02T19:14:40.391423Z ERROR Daemon Daemon Failed to mount resource disk [ResourceDiskError] unable to detect disk topology Oct 2 19:14:40.395391 waagent[1407]: 2023-10-02T19:14:40.395327Z ERROR Daemon Daemon Event: name=WALinuxAgent, op=ActivateResourceDisk, message=[ResourceDiskError] unable to detect disk topology, duration=0 Oct 2 19:14:40.401194 waagent[1407]: 2023-10-02T19:14:40.401130Z INFO Daemon Daemon Clean protocol and wireserver endpoint Oct 2 19:14:40.404169 waagent[1407]: 2023-10-02T19:14:40.404108Z INFO Daemon Daemon Running default provisioning handler Oct 2 19:14:40.414521 waagent[1407]: 2023-10-02T19:14:40.414394Z INFO Daemon Daemon Unable to get cloud-init enabled status from systemctl: Command '['systemctl', 'is-enabled', 'cloud-init-local.service']' returned non-zero exit status 1. Oct 2 19:14:40.420782 waagent[1407]: 2023-10-02T19:14:40.420670Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Oct 2 19:14:40.434173 waagent[1407]: 2023-10-02T19:14:40.421083Z INFO Daemon Daemon cloud-init is enabled: False Oct 2 19:14:40.434173 waagent[1407]: 2023-10-02T19:14:40.421787Z INFO Daemon Daemon Copying ovf-env.xml Oct 2 19:14:40.714968 waagent[1407]: 2023-10-02T19:14:40.714218Z INFO Daemon Daemon Successfully mounted dvd Oct 2 19:14:40.795293 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Oct 2 19:14:40.815219 waagent[1407]: 2023-10-02T19:14:40.815079Z INFO Daemon Daemon Detect protocol endpoint Oct 2 19:14:40.818341 waagent[1407]: 2023-10-02T19:14:40.818253Z INFO Daemon Daemon Clean protocol and wireserver endpoint Oct 2 19:14:40.821133 waagent[1407]: 2023-10-02T19:14:40.821063Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Oct 2 19:14:40.824346 waagent[1407]: 2023-10-02T19:14:40.824276Z INFO Daemon Daemon Test for route to 168.63.129.16 Oct 2 19:14:40.827079 waagent[1407]: 2023-10-02T19:14:40.827014Z INFO Daemon Daemon Route to 168.63.129.16 exists Oct 2 19:14:40.829717 waagent[1407]: 2023-10-02T19:14:40.829654Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Oct 2 19:14:40.961349 waagent[1407]: 2023-10-02T19:14:40.961259Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Oct 2 19:14:40.965810 waagent[1407]: 2023-10-02T19:14:40.965701Z INFO Daemon Daemon Wire protocol version:2012-11-30 Oct 2 19:14:40.968699 waagent[1407]: 2023-10-02T19:14:40.968637Z INFO Daemon Daemon Server preferred version:2015-04-05 Oct 2 19:14:41.253709 waagent[1407]: 2023-10-02T19:14:41.253502Z INFO Daemon Daemon Initializing goal state during protocol detection Oct 2 19:14:41.266935 waagent[1407]: 2023-10-02T19:14:41.266853Z INFO Daemon Daemon Forcing an update of the goal state.. Oct 2 19:14:41.269857 waagent[1407]: 2023-10-02T19:14:41.269789Z INFO Daemon Daemon Fetching goal state [incarnation 1] Oct 2 19:14:41.354329 waagent[1407]: 2023-10-02T19:14:41.354193Z INFO Daemon Daemon Found private key matching thumbprint F1CA99E6E5B03DBF6DA41CFF3A1D2F8C74FE5BFA Oct 2 19:14:41.364573 waagent[1407]: 2023-10-02T19:14:41.354744Z INFO Daemon Daemon Certificate with thumbprint 6D751BD3FF40A6847C1EEBF73FB527AE42708536 has no matching private key. Oct 2 19:14:41.364573 waagent[1407]: 2023-10-02T19:14:41.355823Z INFO Daemon Daemon Fetch goal state completed Oct 2 19:14:41.384547 waagent[1407]: 2023-10-02T19:14:41.384472Z INFO Daemon Daemon Fetched new vmSettings [correlation ID: 0f16deb9-919f-4b10-9187-84ba75f74c11 New eTag: 2323884908983232180] Oct 2 19:14:41.392133 waagent[1407]: 2023-10-02T19:14:41.385392Z INFO Daemon Daemon Status Blob type 'None' is not valid, assuming BlockBlob Oct 2 19:14:41.393984 waagent[1407]: 2023-10-02T19:14:41.393911Z INFO Daemon Daemon Starting provisioning Oct 2 19:14:41.400754 waagent[1407]: 2023-10-02T19:14:41.394235Z INFO Daemon Daemon Handle ovf-env.xml. Oct 2 19:14:41.400754 waagent[1407]: 2023-10-02T19:14:41.395470Z INFO Daemon Daemon Set hostname [ci-3510.3.0-a-aff9975401] Oct 2 19:14:41.417558 waagent[1407]: 2023-10-02T19:14:41.417422Z INFO Daemon Daemon Publish hostname [ci-3510.3.0-a-aff9975401] Oct 2 19:14:41.425275 waagent[1407]: 2023-10-02T19:14:41.418263Z INFO Daemon Daemon Examine /proc/net/route for primary interface Oct 2 19:14:41.425275 waagent[1407]: 2023-10-02T19:14:41.419177Z INFO Daemon Daemon Primary interface is [eth0] Oct 2 19:14:41.432530 systemd[1]: systemd-networkd-wait-online.service: Deactivated successfully. Oct 2 19:14:41.432784 systemd[1]: Stopped systemd-networkd-wait-online.service. Oct 2 19:14:41.432853 systemd[1]: Stopping systemd-networkd-wait-online.service... Oct 2 19:14:41.433171 systemd[1]: Stopping systemd-networkd.service... Oct 2 19:14:41.436984 systemd-networkd[1186]: eth0: DHCPv6 lease lost Oct 2 19:14:41.438258 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 2 19:14:41.438409 systemd[1]: Stopped systemd-networkd.service. Oct 2 19:14:41.440714 systemd[1]: Starting systemd-networkd.service... Oct 2 19:14:41.471410 systemd-networkd[1462]: enP3909s1: Link UP Oct 2 19:14:41.471421 systemd-networkd[1462]: enP3909s1: Gained carrier Oct 2 19:14:41.472740 systemd-networkd[1462]: eth0: Link UP Oct 2 19:14:41.472749 systemd-networkd[1462]: eth0: Gained carrier Oct 2 19:14:41.473274 systemd-networkd[1462]: lo: Link UP Oct 2 19:14:41.473284 systemd-networkd[1462]: lo: Gained carrier Oct 2 19:14:41.473605 systemd-networkd[1462]: eth0: Gained IPv6LL Oct 2 19:14:41.473871 systemd-networkd[1462]: Enumeration completed Oct 2 19:14:41.482494 waagent[1407]: 2023-10-02T19:14:41.475314Z INFO Daemon Daemon Create user account if not exists Oct 2 19:14:41.474012 systemd[1]: Started systemd-networkd.service. Oct 2 19:14:41.478766 systemd[1]: Starting systemd-networkd-wait-online.service... Oct 2 19:14:41.483295 waagent[1407]: 2023-10-02T19:14:41.483182Z INFO Daemon Daemon User core already exists, skip useradd Oct 2 19:14:41.483738 waagent[1407]: 2023-10-02T19:14:41.483676Z INFO Daemon Daemon Configure sudoer Oct 2 19:14:41.485025 waagent[1407]: 2023-10-02T19:14:41.484969Z INFO Daemon Daemon Configure sshd Oct 2 19:14:41.485859 waagent[1407]: 2023-10-02T19:14:41.485808Z INFO Daemon Daemon Deploy ssh public key. Oct 2 19:14:41.488580 systemd-networkd[1462]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 2 19:14:41.535086 systemd-networkd[1462]: eth0: DHCPv4 address 10.200.8.42/24, gateway 10.200.8.1 acquired from 168.63.129.16 Oct 2 19:14:41.538880 systemd[1]: Finished systemd-networkd-wait-online.service. Oct 2 19:14:42.788215 waagent[1407]: 2023-10-02T19:14:42.788119Z INFO Daemon Daemon Provisioning complete Oct 2 19:14:42.804576 waagent[1407]: 2023-10-02T19:14:42.804497Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Oct 2 19:14:42.807486 waagent[1407]: 2023-10-02T19:14:42.807418Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Oct 2 19:14:42.812715 waagent[1407]: 2023-10-02T19:14:42.812650Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.6.0.2 is the most current agent Oct 2 19:14:43.077499 waagent[1471]: 2023-10-02T19:14:43.077330Z INFO ExtHandler ExtHandler Agent WALinuxAgent-2.6.0.2 is running as the goal state agent Oct 2 19:14:43.078230 waagent[1471]: 2023-10-02T19:14:43.078159Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 2 19:14:43.078375 waagent[1471]: 2023-10-02T19:14:43.078320Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 2 19:14:43.089603 waagent[1471]: 2023-10-02T19:14:43.089531Z INFO ExtHandler ExtHandler Forcing an update of the goal state.. Oct 2 19:14:43.089762 waagent[1471]: 2023-10-02T19:14:43.089712Z INFO ExtHandler ExtHandler Fetching goal state [incarnation 1] Oct 2 19:14:43.149576 waagent[1471]: 2023-10-02T19:14:43.149450Z INFO ExtHandler ExtHandler Found private key matching thumbprint F1CA99E6E5B03DBF6DA41CFF3A1D2F8C74FE5BFA Oct 2 19:14:43.149803 waagent[1471]: 2023-10-02T19:14:43.149741Z INFO ExtHandler ExtHandler Certificate with thumbprint 6D751BD3FF40A6847C1EEBF73FB527AE42708536 has no matching private key. Oct 2 19:14:43.150069 waagent[1471]: 2023-10-02T19:14:43.150014Z INFO ExtHandler ExtHandler Fetch goal state completed Oct 2 19:14:43.164192 waagent[1471]: 2023-10-02T19:14:43.164129Z INFO ExtHandler ExtHandler Fetched new vmSettings [correlation ID: fe9badcf-f06f-452f-98ff-b71b1077b4cf New eTag: 2323884908983232180] Oct 2 19:14:43.164764 waagent[1471]: 2023-10-02T19:14:43.164705Z INFO ExtHandler ExtHandler Status Blob type 'None' is not valid, assuming BlockBlob Oct 2 19:14:43.290441 waagent[1471]: 2023-10-02T19:14:43.290294Z INFO ExtHandler ExtHandler Distro: flatcar-3510.3.0; OSUtil: CoreOSUtil; AgentService: waagent; Python: 3.9.16; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Oct 2 19:14:43.299491 waagent[1471]: 2023-10-02T19:14:43.299414Z INFO ExtHandler ExtHandler WALinuxAgent-2.6.0.2 running as process 1471 Oct 2 19:14:43.302823 waagent[1471]: 2023-10-02T19:14:43.302755Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '3510.3.0', '', 'Flatcar Container Linux by Kinvolk'] Oct 2 19:14:43.304047 waagent[1471]: 2023-10-02T19:14:43.303990Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Oct 2 19:14:43.377222 waagent[1471]: 2023-10-02T19:14:43.377146Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Oct 2 19:14:43.377703 waagent[1471]: 2023-10-02T19:14:43.377629Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Oct 2 19:14:43.385815 waagent[1471]: 2023-10-02T19:14:43.385755Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Oct 2 19:14:43.386329 waagent[1471]: 2023-10-02T19:14:43.386264Z ERROR ExtHandler ExtHandler Unable to setup the persistent firewall rules: [Errno 30] Read-only file system: '/lib/systemd/system/waagent-network-setup.service' Oct 2 19:14:43.387417 waagent[1471]: 2023-10-02T19:14:43.387347Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: configuration enabled [False], cgroups enabled [False], python supported: [True] Oct 2 19:14:43.388719 waagent[1471]: 2023-10-02T19:14:43.388657Z INFO ExtHandler ExtHandler Starting env monitor service. Oct 2 19:14:43.389111 waagent[1471]: 2023-10-02T19:14:43.389055Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 2 19:14:43.389261 waagent[1471]: 2023-10-02T19:14:43.389214Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 2 19:14:43.389772 waagent[1471]: 2023-10-02T19:14:43.389714Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Oct 2 19:14:43.390414 waagent[1471]: 2023-10-02T19:14:43.390352Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Oct 2 19:14:43.390414 waagent[1471]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Oct 2 19:14:43.390414 waagent[1471]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Oct 2 19:14:43.390414 waagent[1471]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Oct 2 19:14:43.390414 waagent[1471]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Oct 2 19:14:43.390414 waagent[1471]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Oct 2 19:14:43.390414 waagent[1471]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Oct 2 19:14:43.390822 waagent[1471]: 2023-10-02T19:14:43.390760Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Oct 2 19:14:43.391126 waagent[1471]: 2023-10-02T19:14:43.391069Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 2 19:14:43.391578 waagent[1471]: 2023-10-02T19:14:43.391527Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 2 19:14:43.394190 waagent[1471]: 2023-10-02T19:14:43.394103Z INFO EnvHandler ExtHandler Configure routes Oct 2 19:14:43.394340 waagent[1471]: 2023-10-02T19:14:43.394292Z INFO EnvHandler ExtHandler Gateway:None Oct 2 19:14:43.394468 waagent[1471]: 2023-10-02T19:14:43.394425Z INFO EnvHandler ExtHandler Routes:None Oct 2 19:14:43.395722 waagent[1471]: 2023-10-02T19:14:43.395668Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Oct 2 19:14:43.395794 waagent[1471]: 2023-10-02T19:14:43.395744Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Oct 2 19:14:43.396262 waagent[1471]: 2023-10-02T19:14:43.396203Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Oct 2 19:14:43.396485 waagent[1471]: 2023-10-02T19:14:43.396433Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Oct 2 19:14:43.397473 waagent[1471]: 2023-10-02T19:14:43.397418Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Oct 2 19:14:43.406216 waagent[1471]: 2023-10-02T19:14:43.406173Z INFO ExtHandler ExtHandler Checking for agent updates (family: Prod) Oct 2 19:14:43.406835 waagent[1471]: 2023-10-02T19:14:43.406793Z WARNING ExtHandler ExtHandler Fetch failed: [HttpError] HTTPS is unavailable and required Oct 2 19:14:43.407690 waagent[1471]: 2023-10-02T19:14:43.407642Z INFO ExtHandler ExtHandler [PERIODIC] Request failed using the direct channel. Error: 'NoneType' object has no attribute 'getheaders' Oct 2 19:14:43.421617 waagent[1471]: 2023-10-02T19:14:43.421561Z ERROR EnvHandler ExtHandler Failed to get the PID of the DHCP client: invalid literal for int() with base 10: 'MainPID=1462' Oct 2 19:14:43.463351 waagent[1471]: 2023-10-02T19:14:43.463261Z INFO ExtHandler ExtHandler Default channel changed to HostGA channel. Oct 2 19:14:43.543719 waagent[1471]: 2023-10-02T19:14:43.543594Z INFO MonitorHandler ExtHandler Network interfaces: Oct 2 19:14:43.543719 waagent[1471]: Executing ['ip', '-a', '-o', 'link']: Oct 2 19:14:43.543719 waagent[1471]: 1: lo: mtu 65536 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000\ link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00 Oct 2 19:14:43.543719 waagent[1471]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:d9:7d:e3 brd ff:ff:ff:ff:ff:ff Oct 2 19:14:43.543719 waagent[1471]: 3: enP3909s1: mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:d9:7d:e3 brd ff:ff:ff:ff:ff:ff\ altname enP3909p0s2 Oct 2 19:14:43.543719 waagent[1471]: Executing ['ip', '-4', '-a', '-o', 'address']: Oct 2 19:14:43.543719 waagent[1471]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Oct 2 19:14:43.543719 waagent[1471]: 2: eth0 inet 10.200.8.42/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Oct 2 19:14:43.543719 waagent[1471]: Executing ['ip', '-6', '-a', '-o', 'address']: Oct 2 19:14:43.543719 waagent[1471]: 1: lo inet6 ::1/128 scope host \ valid_lft forever preferred_lft forever Oct 2 19:14:43.543719 waagent[1471]: 2: eth0 inet6 fe80::20d:3aff:fed9:7de3/64 scope link \ valid_lft forever preferred_lft forever Oct 2 19:14:43.853190 waagent[1471]: 2023-10-02T19:14:43.853116Z INFO ExtHandler ExtHandler Agent WALinuxAgent-2.6.0.2 discovered update WALinuxAgent-2.10.0.3 -- exiting Oct 2 19:14:44.817463 waagent[1407]: 2023-10-02T19:14:44.817285Z INFO Daemon Daemon Agent WALinuxAgent-2.6.0.2 launched with command '/usr/share/oem/python/bin/python -u /usr/share/oem/bin/waagent -run-exthandlers' is successfully running Oct 2 19:14:44.825304 waagent[1407]: 2023-10-02T19:14:44.825229Z INFO Daemon Daemon Determined Agent WALinuxAgent-2.10.0.3 to be the latest agent Oct 2 19:14:45.848429 waagent[1500]: 2023-10-02T19:14:45.848317Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.10.0.3) Oct 2 19:14:45.849177 waagent[1500]: 2023-10-02T19:14:45.849107Z INFO ExtHandler ExtHandler OS: flatcar 3510.3.0 Oct 2 19:14:45.849318 waagent[1500]: 2023-10-02T19:14:45.849266Z INFO ExtHandler ExtHandler Python: 3.9.16 Oct 2 19:14:45.858706 waagent[1500]: 2023-10-02T19:14:45.858602Z INFO ExtHandler ExtHandler Distro: flatcar-3510.3.0; OSUtil: CoreOSUtil; AgentService: waagent; Python: 3.9.16; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Oct 2 19:14:45.859116 waagent[1500]: 2023-10-02T19:14:45.859056Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 2 19:14:45.859285 waagent[1500]: 2023-10-02T19:14:45.859236Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 2 19:14:45.870449 waagent[1500]: 2023-10-02T19:14:45.870373Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Oct 2 19:14:45.878221 waagent[1500]: 2023-10-02T19:14:45.878157Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.143 Oct 2 19:14:45.879128 waagent[1500]: 2023-10-02T19:14:45.879070Z INFO ExtHandler Oct 2 19:14:45.879277 waagent[1500]: 2023-10-02T19:14:45.879229Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 49990889-3d9b-4e78-b9f8-2500fa58a54c eTag: 2323884908983232180 source: Fabric] Oct 2 19:14:45.879956 waagent[1500]: 2023-10-02T19:14:45.879892Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Oct 2 19:14:45.881017 waagent[1500]: 2023-10-02T19:14:45.880958Z INFO ExtHandler Oct 2 19:14:45.881151 waagent[1500]: 2023-10-02T19:14:45.881104Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Oct 2 19:14:45.887397 waagent[1500]: 2023-10-02T19:14:45.887346Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Oct 2 19:14:45.887829 waagent[1500]: 2023-10-02T19:14:45.887780Z WARNING ExtHandler ExtHandler Fetch failed: [HttpError] HTTPS is unavailable and required Oct 2 19:14:45.917610 waagent[1500]: 2023-10-02T19:14:45.917524Z INFO ExtHandler ExtHandler Default channel changed to HostGAPlugin channel. Oct 2 19:14:45.980844 waagent[1500]: 2023-10-02T19:14:45.980708Z INFO ExtHandler Downloaded certificate {'thumbprint': 'F1CA99E6E5B03DBF6DA41CFF3A1D2F8C74FE5BFA', 'hasPrivateKey': True} Oct 2 19:14:45.981847 waagent[1500]: 2023-10-02T19:14:45.981779Z INFO ExtHandler Downloaded certificate {'thumbprint': '6D751BD3FF40A6847C1EEBF73FB527AE42708536', 'hasPrivateKey': False} Oct 2 19:14:45.982828 waagent[1500]: 2023-10-02T19:14:45.982763Z INFO ExtHandler Fetch goal state completed Oct 2 19:14:46.004015 waagent[1500]: 2023-10-02T19:14:46.003927Z INFO ExtHandler ExtHandler WALinuxAgent-2.10.0.3 running as process 1500 Oct 2 19:14:46.007264 waagent[1500]: 2023-10-02T19:14:46.007200Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '3510.3.0', '', 'Flatcar Container Linux by Kinvolk'] Oct 2 19:14:46.008702 waagent[1500]: 2023-10-02T19:14:46.008646Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Oct 2 19:14:46.013615 waagent[1500]: 2023-10-02T19:14:46.013560Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Oct 2 19:14:46.014000 waagent[1500]: 2023-10-02T19:14:46.013928Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Oct 2 19:14:46.021834 waagent[1500]: 2023-10-02T19:14:46.021776Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Oct 2 19:14:46.022322 waagent[1500]: 2023-10-02T19:14:46.022263Z ERROR ExtHandler ExtHandler Unable to setup the persistent firewall rules: [Errno 30] Read-only file system: '/lib/systemd/system/waagent-network-setup.service' Oct 2 19:14:46.028300 waagent[1500]: 2023-10-02T19:14:46.028196Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Oct 2 19:14:46.033639 waagent[1500]: 2023-10-02T19:14:46.033577Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: configuration enabled [True], cgroups enabled [False], python supported: [True] Oct 2 19:14:46.035106 waagent[1500]: 2023-10-02T19:14:46.035044Z INFO ExtHandler ExtHandler Starting env monitor service. Oct 2 19:14:46.035516 waagent[1500]: 2023-10-02T19:14:46.035459Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 2 19:14:46.035672 waagent[1500]: 2023-10-02T19:14:46.035623Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 2 19:14:46.036224 waagent[1500]: 2023-10-02T19:14:46.036167Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Oct 2 19:14:46.036504 waagent[1500]: 2023-10-02T19:14:46.036450Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Oct 2 19:14:46.036504 waagent[1500]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Oct 2 19:14:46.036504 waagent[1500]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Oct 2 19:14:46.036504 waagent[1500]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Oct 2 19:14:46.036504 waagent[1500]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Oct 2 19:14:46.036504 waagent[1500]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Oct 2 19:14:46.036504 waagent[1500]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Oct 2 19:14:46.039003 waagent[1500]: 2023-10-02T19:14:46.038868Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Oct 2 19:14:46.039832 waagent[1500]: 2023-10-02T19:14:46.039768Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Oct 2 19:14:46.040380 waagent[1500]: 2023-10-02T19:14:46.040322Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 2 19:14:46.040464 waagent[1500]: 2023-10-02T19:14:46.040407Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Oct 2 19:14:46.041142 waagent[1500]: 2023-10-02T19:14:46.041089Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 2 19:14:46.044532 waagent[1500]: 2023-10-02T19:14:46.044438Z INFO EnvHandler ExtHandler Configure routes Oct 2 19:14:46.044802 waagent[1500]: 2023-10-02T19:14:46.044738Z INFO EnvHandler ExtHandler Gateway:None Oct 2 19:14:46.049201 waagent[1500]: 2023-10-02T19:14:46.049105Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Oct 2 19:14:46.049475 waagent[1500]: 2023-10-02T19:14:46.049422Z INFO EnvHandler ExtHandler Routes:None Oct 2 19:14:46.049872 waagent[1500]: 2023-10-02T19:14:46.049819Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Oct 2 19:14:46.050395 waagent[1500]: 2023-10-02T19:14:46.050342Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Oct 2 19:14:46.054741 waagent[1500]: 2023-10-02T19:14:46.054620Z INFO MonitorHandler ExtHandler Network interfaces: Oct 2 19:14:46.054741 waagent[1500]: Executing ['ip', '-a', '-o', 'link']: Oct 2 19:14:46.054741 waagent[1500]: 1: lo: mtu 65536 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000\ link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00 Oct 2 19:14:46.054741 waagent[1500]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:d9:7d:e3 brd ff:ff:ff:ff:ff:ff Oct 2 19:14:46.054741 waagent[1500]: 3: enP3909s1: mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:d9:7d:e3 brd ff:ff:ff:ff:ff:ff\ altname enP3909p0s2 Oct 2 19:14:46.054741 waagent[1500]: Executing ['ip', '-4', '-a', '-o', 'address']: Oct 2 19:14:46.054741 waagent[1500]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Oct 2 19:14:46.054741 waagent[1500]: 2: eth0 inet 10.200.8.42/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Oct 2 19:14:46.054741 waagent[1500]: Executing ['ip', '-6', '-a', '-o', 'address']: Oct 2 19:14:46.054741 waagent[1500]: 1: lo inet6 ::1/128 scope host \ valid_lft forever preferred_lft forever Oct 2 19:14:46.054741 waagent[1500]: 2: eth0 inet6 fe80::20d:3aff:fed9:7de3/64 scope link \ valid_lft forever preferred_lft forever Oct 2 19:14:46.073640 waagent[1500]: 2023-10-02T19:14:46.073558Z INFO ExtHandler ExtHandler Downloading agent manifest Oct 2 19:14:46.129546 waagent[1500]: 2023-10-02T19:14:46.129473Z INFO ExtHandler ExtHandler Oct 2 19:14:46.130708 waagent[1500]: 2023-10-02T19:14:46.130642Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 439e188c-f30f-483c-8472-a50c76e68a8b correlation e2687599-0e02-4609-9595-d54cb876a1d4 created: 2023-10-02T19:13:18.707390Z] Oct 2 19:14:46.131769 waagent[1500]: 2023-10-02T19:14:46.131703Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Oct 2 19:14:46.133553 waagent[1500]: 2023-10-02T19:14:46.133494Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 4 ms] Oct 2 19:14:46.164681 waagent[1500]: 2023-10-02T19:14:46.164605Z INFO ExtHandler ExtHandler Looking for existing remote access users. Oct 2 19:14:46.185522 waagent[1500]: 2023-10-02T19:14:46.185425Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.10.0.3 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 24026B4B-928D-4617-B837-DD5631BFD196;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 1] Oct 2 19:14:46.218616 waagent[1500]: 2023-10-02T19:14:46.218493Z INFO EnvHandler ExtHandler Successfully added Azure fabric firewall rules. Current Firewall rules: Oct 2 19:14:46.218616 waagent[1500]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Oct 2 19:14:46.218616 waagent[1500]: pkts bytes target prot opt in out source destination Oct 2 19:14:46.218616 waagent[1500]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Oct 2 19:14:46.218616 waagent[1500]: pkts bytes target prot opt in out source destination Oct 2 19:14:46.218616 waagent[1500]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Oct 2 19:14:46.218616 waagent[1500]: pkts bytes target prot opt in out source destination Oct 2 19:14:46.218616 waagent[1500]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Oct 2 19:14:46.218616 waagent[1500]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Oct 2 19:14:46.218616 waagent[1500]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Oct 2 19:14:46.225816 waagent[1500]: 2023-10-02T19:14:46.225702Z INFO EnvHandler ExtHandler Current Firewall rules: Oct 2 19:14:46.225816 waagent[1500]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Oct 2 19:14:46.225816 waagent[1500]: pkts bytes target prot opt in out source destination Oct 2 19:14:46.225816 waagent[1500]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Oct 2 19:14:46.225816 waagent[1500]: pkts bytes target prot opt in out source destination Oct 2 19:14:46.225816 waagent[1500]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Oct 2 19:14:46.225816 waagent[1500]: pkts bytes target prot opt in out source destination Oct 2 19:14:46.225816 waagent[1500]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Oct 2 19:14:46.225816 waagent[1500]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Oct 2 19:14:46.225816 waagent[1500]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Oct 2 19:14:46.226419 waagent[1500]: 2023-10-02T19:14:46.226361Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Oct 2 19:15:12.441101 kernel: hv_balloon: Max. dynamic memory size: 8192 MB Oct 2 19:15:18.484306 update_engine[1310]: I1002 19:15:18.484210 1310 update_attempter.cc:505] Updating boot flags... Oct 2 19:15:26.128158 systemd[1]: Created slice system-sshd.slice. Oct 2 19:15:26.129692 systemd[1]: Started sshd@0-10.200.8.42:22-10.200.12.6:39588.service. Oct 2 19:15:27.009818 sshd[1590]: Accepted publickey for core from 10.200.12.6 port 39588 ssh2: RSA SHA256:gmG02UXHBKapD9vqiBZ3w7SUJvWJJQwqYxETXcCINW8 Oct 2 19:15:27.011460 sshd[1590]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 2 19:15:27.015008 systemd-logind[1308]: New session 3 of user core. Oct 2 19:15:27.016512 systemd[1]: Started session-3.scope. Oct 2 19:15:27.563683 systemd[1]: Started sshd@1-10.200.8.42:22-10.200.12.6:55052.service. Oct 2 19:15:28.192135 sshd[1595]: Accepted publickey for core from 10.200.12.6 port 55052 ssh2: RSA SHA256:gmG02UXHBKapD9vqiBZ3w7SUJvWJJQwqYxETXcCINW8 Oct 2 19:15:28.193754 sshd[1595]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 2 19:15:28.198613 systemd[1]: Started session-4.scope. Oct 2 19:15:28.199083 systemd-logind[1308]: New session 4 of user core. Oct 2 19:15:28.639649 sshd[1595]: pam_unix(sshd:session): session closed for user core Oct 2 19:15:28.642825 systemd[1]: sshd@1-10.200.8.42:22-10.200.12.6:55052.service: Deactivated successfully. Oct 2 19:15:28.643713 systemd[1]: session-4.scope: Deactivated successfully. Oct 2 19:15:28.644342 systemd-logind[1308]: Session 4 logged out. Waiting for processes to exit. Oct 2 19:15:28.645105 systemd-logind[1308]: Removed session 4. Oct 2 19:15:28.743433 systemd[1]: Started sshd@2-10.200.8.42:22-10.200.12.6:55066.service. Oct 2 19:15:29.364531 sshd[1601]: Accepted publickey for core from 10.200.12.6 port 55066 ssh2: RSA SHA256:gmG02UXHBKapD9vqiBZ3w7SUJvWJJQwqYxETXcCINW8 Oct 2 19:15:29.366229 sshd[1601]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 2 19:15:29.370927 systemd[1]: Started session-5.scope. Oct 2 19:15:29.371555 systemd-logind[1308]: New session 5 of user core. Oct 2 19:15:29.800366 sshd[1601]: pam_unix(sshd:session): session closed for user core Oct 2 19:15:29.803891 systemd[1]: sshd@2-10.200.8.42:22-10.200.12.6:55066.service: Deactivated successfully. Oct 2 19:15:29.804870 systemd[1]: session-5.scope: Deactivated successfully. Oct 2 19:15:29.805503 systemd-logind[1308]: Session 5 logged out. Waiting for processes to exit. Oct 2 19:15:29.806242 systemd-logind[1308]: Removed session 5. Oct 2 19:15:29.906933 systemd[1]: Started sshd@3-10.200.8.42:22-10.200.12.6:55068.service. Oct 2 19:15:30.768377 sshd[1607]: Accepted publickey for core from 10.200.12.6 port 55068 ssh2: RSA SHA256:gmG02UXHBKapD9vqiBZ3w7SUJvWJJQwqYxETXcCINW8 Oct 2 19:15:30.770064 sshd[1607]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 2 19:15:30.775780 systemd[1]: Started session-6.scope. Oct 2 19:15:30.776416 systemd-logind[1308]: New session 6 of user core. Oct 2 19:15:31.345381 sshd[1607]: pam_unix(sshd:session): session closed for user core Oct 2 19:15:31.348332 systemd[1]: sshd@3-10.200.8.42:22-10.200.12.6:55068.service: Deactivated successfully. Oct 2 19:15:31.349183 systemd[1]: session-6.scope: Deactivated successfully. Oct 2 19:15:31.349800 systemd-logind[1308]: Session 6 logged out. Waiting for processes to exit. Oct 2 19:15:31.350541 systemd-logind[1308]: Removed session 6. Oct 2 19:15:31.449518 systemd[1]: Started sshd@4-10.200.8.42:22-10.200.12.6:55076.service. Oct 2 19:15:32.073681 sshd[1613]: Accepted publickey for core from 10.200.12.6 port 55076 ssh2: RSA SHA256:gmG02UXHBKapD9vqiBZ3w7SUJvWJJQwqYxETXcCINW8 Oct 2 19:15:32.075376 sshd[1613]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 2 19:15:32.080924 systemd[1]: Started session-7.scope. Oct 2 19:15:32.081677 systemd-logind[1308]: New session 7 of user core. Oct 2 19:15:32.848904 sudo[1616]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Oct 2 19:15:32.849237 sudo[1616]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 2 19:15:32.874179 dbus-daemon[1298]: Э\u0002\xd01V: received setenforce notice (enforcing=-110709504) Oct 2 19:15:32.876348 sudo[1616]: pam_unix(sudo:session): session closed for user root Oct 2 19:15:32.992871 sshd[1613]: pam_unix(sshd:session): session closed for user core Oct 2 19:15:32.996633 systemd[1]: sshd@4-10.200.8.42:22-10.200.12.6:55076.service: Deactivated successfully. Oct 2 19:15:32.997772 systemd[1]: session-7.scope: Deactivated successfully. Oct 2 19:15:32.998609 systemd-logind[1308]: Session 7 logged out. Waiting for processes to exit. Oct 2 19:15:32.999627 systemd-logind[1308]: Removed session 7. Oct 2 19:15:33.098936 systemd[1]: Started sshd@5-10.200.8.42:22-10.200.12.6:55090.service. Oct 2 19:15:33.722877 sshd[1620]: Accepted publickey for core from 10.200.12.6 port 55090 ssh2: RSA SHA256:gmG02UXHBKapD9vqiBZ3w7SUJvWJJQwqYxETXcCINW8 Oct 2 19:15:33.725988 sshd[1620]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 2 19:15:33.730598 systemd[1]: Started session-8.scope. Oct 2 19:15:33.731201 systemd-logind[1308]: New session 8 of user core. Oct 2 19:15:34.067023 sudo[1624]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Oct 2 19:15:34.067607 sudo[1624]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 2 19:15:34.070671 sudo[1624]: pam_unix(sudo:session): session closed for user root Oct 2 19:15:34.075255 sudo[1623]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/systemctl restart audit-rules Oct 2 19:15:34.075518 sudo[1623]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 2 19:15:34.084258 systemd[1]: Stopping audit-rules.service... Oct 2 19:15:34.084000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Oct 2 19:15:34.088085 auditctl[1627]: No rules Oct 2 19:15:34.084000 audit[1627]: SYSCALL arch=c000003e syscall=44 success=yes exit=1056 a0=3 a1=7ffe7d7dc410 a2=420 a3=0 items=0 ppid=1 pid=1627 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditctl" exe="/usr/sbin/auditctl" subj=system_u:system_r:kernel_t:s0 key=(null) Oct 2 19:15:34.090180 systemd[1]: audit-rules.service: Deactivated successfully. Oct 2 19:15:34.090339 systemd[1]: Stopped audit-rules.service. Oct 2 19:15:34.091866 systemd[1]: Starting audit-rules.service... Oct 2 19:15:34.105371 kernel: audit: type=1305 audit(1696274134.084:165): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Oct 2 19:15:34.105442 kernel: audit: type=1300 audit(1696274134.084:165): arch=c000003e syscall=44 success=yes exit=1056 a0=3 a1=7ffe7d7dc410 a2=420 a3=0 items=0 ppid=1 pid=1627 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditctl" exe="/usr/sbin/auditctl" subj=system_u:system_r:kernel_t:s0 key=(null) Oct 2 19:15:34.105473 kernel: audit: type=1327 audit(1696274134.084:165): proctitle=2F7362696E2F617564697463746C002D44 Oct 2 19:15:34.084000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D44 Oct 2 19:15:34.089000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=audit-rules comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:15:34.114284 augenrules[1644]: No rules Oct 2 19:15:34.115104 systemd[1]: Finished audit-rules.service. Oct 2 19:15:34.117265 sudo[1623]: pam_unix(sudo:session): session closed for user root Oct 2 19:15:34.118511 kernel: audit: type=1131 audit(1696274134.089:166): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=audit-rules comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:15:34.118563 kernel: audit: type=1130 audit(1696274134.114:167): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=audit-rules comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:15:34.114000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=audit-rules comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:15:34.127989 kernel: audit: type=1106 audit(1696274134.116:168): pid=1623 uid=500 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Oct 2 19:15:34.116000 audit[1623]: USER_END pid=1623 uid=500 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Oct 2 19:15:34.116000 audit[1623]: CRED_DISP pid=1623 uid=500 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Oct 2 19:15:34.148189 kernel: audit: type=1104 audit(1696274134.116:169): pid=1623 uid=500 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Oct 2 19:15:34.217121 sshd[1620]: pam_unix(sshd:session): session closed for user core Oct 2 19:15:34.217000 audit[1620]: USER_END pid=1620 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.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Oct 2 19:15:34.220518 systemd[1]: sshd@5-10.200.8.42:22-10.200.12.6:55090.service: Deactivated successfully. Oct 2 19:15:34.221401 systemd[1]: session-8.scope: Deactivated successfully. Oct 2 19:15:34.223091 systemd-logind[1308]: Session 8 logged out. Waiting for processes to exit. Oct 2 19:15:34.224180 systemd-logind[1308]: Removed session 8. Oct 2 19:15:34.217000 audit[1620]: CRED_DISP pid=1620 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.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Oct 2 19:15:34.244450 kernel: audit: type=1106 audit(1696274134.217:170): pid=1620 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.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Oct 2 19:15:34.244533 kernel: audit: type=1104 audit(1696274134.217:171): pid=1620 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.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Oct 2 19:15:34.244562 kernel: audit: type=1131 audit(1696274134.219:172): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@5-10.200.8.42:22-10.200.12.6:55090 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:15:34.219000 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.200.8.42:22-10.200.12.6:55090 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:15:34.325663 systemd[1]: Started sshd@6-10.200.8.42:22-10.200.12.6:55098.service. Oct 2 19:15:34.324000 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.200.8.42:22-10.200.12.6:55098 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:15:34.955000 audit[1650]: USER_ACCT pid=1650 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.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Oct 2 19:15:34.956572 sshd[1650]: Accepted publickey for core from 10.200.12.6 port 55098 ssh2: RSA SHA256:gmG02UXHBKapD9vqiBZ3w7SUJvWJJQwqYxETXcCINW8 Oct 2 19:15:34.956000 audit[1650]: CRED_ACQ pid=1650 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.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Oct 2 19:15:34.956000 audit[1650]: SYSCALL arch=c000003e syscall=1 success=yes exit=3 a0=5 a1=7ffcace0e600 a2=3 a3=0 items=0 ppid=1 pid=1650 auid=500 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=9 comm="sshd" exe="/usr/sbin/sshd" subj=system_u:system_r:kernel_t:s0 key=(null) Oct 2 19:15:34.956000 audit: PROCTITLE proctitle=737368643A20636F7265205B707269765D Oct 2 19:15:34.958258 sshd[1650]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 2 19:15:34.963874 systemd[1]: Started session-9.scope. Oct 2 19:15:34.964623 systemd-logind[1308]: New session 9 of user core. Oct 2 19:15:34.968000 audit[1650]: USER_START pid=1650 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.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Oct 2 19:15:34.969000 audit[1652]: CRED_ACQ pid=1652 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.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Oct 2 19:15:35.319000 audit[1653]: USER_ACCT pid=1653 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' Oct 2 19:15:35.319000 audit[1653]: CRED_REFR pid=1653 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' Oct 2 19:15:35.320131 sudo[1653]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/cat /var/resource/data Oct 2 19:15:35.320475 sudo[1653]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 2 19:15:35.321000 audit[1653]: USER_START pid=1653 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' Oct 2 19:15:35.324092 sudo[1653]: pam_unix(sudo:session): session closed for user root Oct 2 19:15:35.323000 audit[1653]: USER_END pid=1653 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' Oct 2 19:15:35.323000 audit[1653]: CRED_DISP pid=1653 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' Oct 2 19:15:35.424299 sshd[1650]: pam_unix(sshd:session): session closed for user core Oct 2 19:15:35.424000 audit[1650]: USER_END pid=1650 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.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Oct 2 19:15:35.425000 audit[1650]: CRED_DISP pid=1650 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.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Oct 2 19:15:35.427289 systemd[1]: sshd@6-10.200.8.42:22-10.200.12.6:55098.service: Deactivated successfully. Oct 2 19:15:35.426000 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.200.8.42:22-10.200.12.6:55098 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 2 19:15:35.428172 systemd[1]: session-9.scope: Deactivated successfully. Oct 2 19:15:35.428791 systemd-logind[1308]: Session 9 logged out. Waiting for processes to exit. Oct 2 19:15:35.429534 systemd-logind[1308]: Removed session 9.