[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.17.0-8.fc42 06/10/2025 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 420330828 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2421 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE22BD 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 00227D (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2331 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE23C1 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE23F9 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe22bd-0xbffe2330] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22bc] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2331-0xbffe23c0] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe23c1-0xbffe23f8] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe23f9-0xbffe2420] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524584K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001013] APIC: Switch to symmetric I/O mode setup [ 0.003141] x2apic enabled [ 0.004010] Switched APIC routing to physical x2apic. [ 0.005015] kvm-guest: setup PV IPIs [ 0.008849] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.009000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.009021] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.011009] pid_max: default: 32768 minimum: 301 [ 0.013088] LSM: Security Framework initializing [ 0.014058] Yama: becoming mindful. [ 0.015049] SELinux: Initializing. [ 0.016103] *** VALIDATE selinux *** [ 0.024743] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.028596] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.029155] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030140] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031107] *** VALIDATE tmpfs *** [ 0.033093] *** VALIDATE proc *** [ 0.034271] *** VALIDATE cgroup *** [ 0.035013] *** VALIDATE cgroup2 *** [ 0.036248] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.038104] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.040005] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.041032] Spectre V2 : User space: Vulnerable [ 0.042013] Speculative Store Bypass: Vulnerable [ 0.045199] debug: unmapping init [mem 0xffffffff97a59000-0xffffffff97a60fff] [ 0.048229] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.049712] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.050032] ... version: 2 [ 0.051017] ... bit width: 48 [ 0.052016] ... generic registers: 4 [ 0.053016] ... value mask: 0000ffffffffffff [ 0.054016] ... max period: 00007fffffffffff [ 0.055015] ... fixed-purpose events: 3 [ 0.056016] ... event mask: 000000070000000f [ 0.057344] rcu: Hierarchical SRCU implementation. [ 0.059572] smp: Bringing up secondary CPUs ... [ 0.060606] x86: Booting SMP configuration: [ 0.061030] .... node #0, CPUs: #1 #2 #3 [ 0.066094] smp: Brought up 1 node, 4 CPUs [ 0.068012] smpboot: Max logical packages: 1 [ 0.069013] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.155661] node 0 deferred pages initialised in 84ms [ 0.160163] devtmpfs: initialized [ 0.161377] x86/mm: Memory block size: 128MB [ 0.164969] gcov: version magic: 0x41383552 [ 0.167143] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.171077] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.173293] pinctrl core: initialized pinctrl subsystem [ 0.175202] [ 0.175863] ************************************************************* [ 0.178015] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.181017] ** ** [ 0.184014] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.186012] ** ** [ 0.189015] ** This means that this kernel is built to expose internal ** [ 0.191023] ** IOMMU data structures, which may compromise security on ** [ 0.194015] ** your system. ** [ 0.196013] ** ** [ 0.199015] ** If you see this message and you are not debugging the ** [ 0.202028] ** kernel, report this immediately to your vendor! ** [ 0.204014] ** ** [ 0.207014] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.209013] ************************************************************* [ 0.211679] NET: Registered protocol family 16 [ 0.214391] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.217068] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.219058] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.223145] cpuidle: using governor menu [ 0.224604] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.227453] PCI: Using configuration type 1 for base access [ 0.229139] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.237140] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.238019] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.240076] cryptd: max_cpu_qlen set to 1000 [ 0.243229] ACPI: Added _OSI(Module Device) [ 0.244016] ACPI: Added _OSI(Processor Device) [ 0.246015] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.248016] ACPI: Added _OSI(Processor Aggregator Device) [ 0.252263] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.257289] ACPI: Interpreter enabled [ 0.258062] ACPI: PM: (supports S0 S3 S4 S5) [ 0.260016] ACPI: Using IOAPIC for interrupt routing [ 0.262096] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.263431] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.272796] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.275047] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.277030] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.281164] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.287371] acpiphp: Slot [2] registered [ 0.288141] acpiphp: Slot [5] registered [ 0.289054] acpiphp: Slot [6] registered [ 0.290000] acpiphp: Slot [3] registered [ 0.290000] acpiphp: Slot [4] registered [ 0.291084] acpiphp: Slot [7] registered [ 0.293085] acpiphp: Slot [8] registered [ 0.294044] acpiphp: Slot [9] registered [ 0.294866] acpiphp: Slot [10] registered [ 0.295115] acpiphp: Slot [11] registered [ 0.296075] acpiphp: Slot [12] registered [ 0.297090] acpiphp: Slot [13] registered [ 0.298096] acpiphp: Slot [14] registered [ 0.300084] acpiphp: Slot [15] registered [ 0.301042] acpiphp: Slot [16] registered [ 0.302055] acpiphp: Slot [17] registered [ 0.303020] acpiphp: Slot [18] registered [ 0.303987] acpiphp: Slot [19] registered [ 0.305094] acpiphp: Slot [20] registered [ 0.306100] acpiphp: Slot [21] registered [ 0.307000] acpiphp: Slot [22] registered [ 0.307000] acpiphp: Slot [23] registered [ 0.309133] acpiphp: Slot [24] registered [ 0.311112] acpiphp: Slot [25] registered [ 0.312111] acpiphp: Slot [26] registered [ 0.313087] acpiphp: Slot [27] registered [ 0.314056] acpiphp: Slot [28] registered [ 0.315041] acpiphp: Slot [29] registered [ 0.316051] acpiphp: Slot [30] registered [ 0.317126] acpiphp: Slot [31] registered [ 0.318088] PCI host bridge to bus 0000:00 [ 0.320019] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.321020] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.324020] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.325018] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.327020] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.330035] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.332189] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.335046] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.339000] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.345017] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.349559] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.352019] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.355018] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.357018] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.360099] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.362810] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.365047] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.368823] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.373014] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.382014] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.386948] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.391939] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.396016] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.401014] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.413017] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.420939] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.426016] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.431017] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.443041] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.453867] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.456499] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.459447] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.461464] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.464300] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.469184] iommu: Default domain type: Passthrough [ 0.470379] SCSI subsystem initialized [ 0.472117] ACPI: bus type USB registered [ 0.473115] usbcore: registered new interface driver usbfs [ 0.474050] usbcore: registered new interface driver hub [ 0.476086] usbcore: registered new device driver usb [ 0.477151] pps_core: LinuxPPS API ver. 1 registered [ 0.478009] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.481051] PTP clock support registered [ 0.482142] EDAC MC: Ver: 3.0.0 [ 0.484153] PCI: Using ACPI for IRQ routing [ 0.485610] NetLabel: Initializing [ 0.486011] NetLabel: domain hash size = 128 [ 0.487010] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.490086] NetLabel: unlabeled traffic allowed by default [ 0.492161] vgaarb: loaded [ 0.494223] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.496013] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.502019] clocksource: Switched to clocksource kvm-clock [ 0.611528] VFS: Disk quotas dquot_6.6.0 [ 0.612928] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.615778] *** VALIDATE ramfs *** [ 0.617179] *** VALIDATE hugetlbfs *** [ 0.618890] pnp: PnP ACPI init [ 0.621280] pnp: PnP ACPI: found 6 devices [ 0.665031] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.668811] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.671343] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.673918] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.676755] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.679402] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.682481] NET: Registered protocol family 2 [ 0.685081] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.690456] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.694353] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.699692] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.702568] TCP: Hash tables configured (established 65536 bind 65536) [ 0.705035] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.707358] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.710228] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.713394] NET: Registered protocol family 1 [ 0.716366] RPC: Registered named UNIX socket transport module. [ 0.718527] RPC: Registered udp transport module. [ 0.719884] RPC: Registered tcp transport module. [ 0.721595] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.724183] NET: Registered protocol family 44 [ 0.725849] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.727989] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.730135] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.732509] PCI: CLS 0 bytes, default 64 [ 0.734369] Unpacking initramfs... [ 2.071157] debug: unmapping init [mem 0xffff9c257cc64000-0xffff9c257ffcffff] [ 2.075085] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.076851] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.078879] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.585687] Initialise system trusted keyrings [ 2.587561] Key type blacklist registered [ 2.589521] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.599510] zbud: loaded [ 2.602956] *** VALIDATE nfs *** [ 2.604604] *** VALIDATE nfs4 *** [ 2.606419] pstore: using deflate compression [ 2.610684] Platform Keyring initialized [ 2.706713] NET: Registered protocol family 38 [ 2.708548] Key type asymmetric registered [ 2.710340] Asymmetric key parser 'x509' registered [ 2.712251] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.715628] io scheduler mq-deadline registered [ 2.717558] io scheduler kyber registered [ 2.719561] io scheduler bfq registered [ 2.721593] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.724634] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.727643] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.730499] ACPI: Power Button [PWRF] [ 2.735810] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.742458] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.750894] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.776408] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.803885] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.808633] Non-volatile memory driver v1.3 [ 2.810211] Linux agpgart interface v0.103 [ 2.842996] virtio_blk virtio1: [vda] 134712 512-byte logical blocks (69.0 MB/65.8 MiB) [ 2.846266] vda: detected capacity change from 0 to 68972544 [ 2.879440] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.884477] vdb: detected capacity change from 0 to 1073741824 [ 2.895911] libphy: Fixed MDIO Bus: probed [ 2.906500] usbcore: registered new interface driver usbserial_generic [ 2.908866] usbserial: USB Serial support registered for generic [ 2.911604] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.916465] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.918587] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.921378] mousedev: PS/2 mouse device common for all mice [ 2.924839] rtc_cmos 00:05: RTC can wake from S4 [ 2.927835] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.929060] rtc_cmos 00:05: registered as rtc0 [ 2.933852] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.937496] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 2.938319] intel_pstate: CPU model not supported [ 2.942945] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 2.949385] hid: raw HID events driver (C) Jiri Kosina [ 2.951568] usbcore: registered new interface driver usbhid [ 2.953876] usbhid: USB HID core driver [ 2.955763] drop_monitor: Initializing network drop monitor service [ 2.958436] Initializing XFRM netlink socket [ 2.960690] NET: Registered protocol family 10 [ 2.964513] Segment Routing with IPv6 [ 2.965970] NET: Registered protocol family 17 [ 2.968046] mpls_gso: MPLS GSO support [ 2.974420] RAS: Correctable Errors collector initialized. [ 2.976552] AVX version of gcm_enc/dec engaged. [ 2.978568] AES CTR mode by8 optimization enabled [ 3.066816] sched_clock: Marking stable (3066780084, 0)->(3982857363, -916077279) [ 3.070847] registered taskstats version 1 [ 3.073233] Loading compiled-in X.509 certificates [ 3.075884] zswap: loaded using pool lzo/zbud [ 3.103200] Key type big_key registered [ 3.116473] Key type encrypted registered [ 3.118343] ima: No TPM chip found, activating TPM-bypass! [ 3.120469] ima: Allocated hash algorithm: sha1 [ 3.122451] ima: No architecture policies found [ 3.124048] evm: Initialising EVM extended attributes: [ 3.125707] evm: security.selinux [ 3.126667] evm: security.ima [ 3.127547] evm: security.capability [ 3.128569] evm: HMAC attrs: 0x1 [ 3.130541] rtc_cmos 00:05: setting system clock to 2026-03-02 16:50:51 UTC (1772470251) [ 3.137091] debug: unmapping init [mem 0xffffffff98a03000-0xffffffff98bfffff] [ 3.140035] debug: unmapping init [mem 0xffffffff97782000-0xffffffff97a58fff] [ 3.149135] Write protecting the kernel read-only data: 28672k [ 3.152811] debug: unmapping init [mem 0xffffffff95e03000-0xffffffff95ffffff] [ 3.155766] debug: unmapping init [mem 0xffffffff96714000-0xffffffff967fffff] [ 3.187150] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 3.195352] systemd[1]: Detected virtualization kvm. [ 3.197151] systemd[1]: Detected architecture x86-64. [ 3.198696] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.222571] systemd[1]: No hostname configured. [ 3.224054] systemd[1]: Set hostname to . [ 3.226360] random: systemd: uninitialized urandom read (16 bytes read) [ 3.228776] systemd[1]: Initializing machine ID from random generator. [ 3.352578] random: systemd: uninitialized urandom read (16 bytes read) [ 3.355233] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ 3.359112] random: systemd: uninitialized urandom read (16 bytes read) [ 3.364988] systemd[1]: Starting Setup Virtual Console... Starting Setup Virtual Console... [ 3.369110] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ OK ] Listening on udev Control Socket. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Memstrack Anylazing Service. [ OK ] Reached target Slices. [ OK ] Reached target Timers. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Initrd Root Device. Starting Apply Kernel Variables... [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Reached target Paths. Starting Journal Service... [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Sockets. [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 3.994190] device-mapper: uevent: version 1.0.3 [ 3.996417] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 4.867434] virtio_net virtio0 ens2: renamed from eth0 [ 4.877059] random: fast init done [ 4.949668] scsi host0: ata_piix [ 4.957193] scsi host1: ata_piix [ 4.959689] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.963310] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.713711] dracut-initqueue[586]: RTNETLINK answers: File exists [ 9.632985] random: crng init done [ 9.634255] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 10.118605] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Timers. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Paths. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.252663] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.551264] SELinux: Disabled at runtime. [ 11.604082] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 11.611865] systemd[1]: Detected virtualization kvm. [ 11.613629] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.042689] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.046026] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.052542] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.057154] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.065608] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.076300] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.081736] systemd[1]: Created slice system-serial\x2dgetty.slice. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Reached target rpc_pipefs.target. Starting Apply Kernel Variables... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on Process Core Dump Socket. Starting Remount Root and Kernel File Systems... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Stopped target Initrd Root File System. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Created slice system-getty.slice. Mounting Kernel Debug File System... [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. Mounting Huge Pages File System... [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Local Encrypted Volumes. Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... Mounting POSIX Message Queue File System... [ 12.241448] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Started Journal Service. [ OK ] Started Apply Kernel Variables. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted Huge Pages File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... Starting Configure read-only root support... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ OK ] Started udev Coldplug all Devices. [ 12.509259] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 12.803747] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 12.805269] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 12.905819] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 12.922711] EDAC sbridge: Ver: 1.1.2 [ 13.953481] Key type dns_resolver registered [ 14.263327] NFS: Registering the id_resolver key type [ 14.264964] Key type id_resolver registered [ 14.266218] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started dnf makecache --timer. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started irqbalance daemon. Starting Login Service... [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... Starting Restore /run/initramfs on shutdown... [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... Starting Network Manager Wait Online... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting System Logging Service... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg257-client login: [ 70.703243] libcfs: loading out-of-tree module taints kernel. [ 71.131090] Key type ._llcrypt registered [ 71.133591] Key type .llcrypt registered [ 71.794923] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 71.818483] alg: No test for adler32 (adler32-zlib) [ 73.731122] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 75.059734] LNet: Added LNI 192.168.202.57@tcp [8/256/0/180] [ 76.847516] Key type lgssc registered [ 78.913568] Lustre: Echo OBD driver; http://www.lustre.org/ [ 273.846429] Lustre: Mounted lustre-client [ 278.868478] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 299.488231] Lustre: lustre-OST0000-osc-ffff9c25c5ac8800: disconnect after 23s idle [ 300.322605] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing check_logdir /tmp/testlogs/ [ 306.468198] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing yml_node [ 310.694362] Lustre: DEBUG MARKER: Client: 2.17.50.194 [ 313.199246] Lustre: DEBUG MARKER: MDS: 2.17.50.194 [ 315.603388] Lustre: DEBUG MARKER: OSS: 2.17.50.194 [ 317.406713] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Mon Mar 2 11:56:04 EST 2026 [ 340.836833] Lustre: DEBUG MARKER: excepting tests: 32newtarball [ 343.121784] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 346.861368] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 357.199570] Lustre: Unmounted lustre-client [ 483.628624] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 487.467351] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 493.885126] hrtimer: interrupt took 13093200 ns [ 514.202382] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 569.622104] Lustre: DEBUG MARKER: == conf-sanity test 121: failover MGS ==================== 12:00:15 (1772470815) [ 624.996314] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 629.928516] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 677.633653] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mgc.*.mgs_server_uuid [ 734.540833] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 738.668344] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 787.480299] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mgc.*.mgs_server_uuid [ 822.759179] Lustre: DEBUG MARKER: == conf-sanity test 122a: Check OST sequence update ====== 12:04:28 (1772471068) [ 962.208380] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 968.998328] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 998.630507] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 999.689424] Lustre: Mounted lustre-client [ 1025.119902] LustreError: 21169:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c25c6672000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1025.737726] Lustre: Unmounted lustre-client [ 1066.747399] Key type lgssc unregistered [ 1067.109767] LNet: 21575:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1067.134047] LNetError: 21575:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1067.157684] LNet: Removed LNI 192.168.202.57@tcp [ 1068.561201] Key type .llcrypt unregistered [ 1068.562929] Key type ._llcrypt unregistered [ 1091.375262] Key type ._llcrypt registered [ 1091.380536] Key type .llcrypt registered [ 1093.735597] Lustre: DEBUG MARKER: == conf-sanity test 123aa: llog_print works with FIDs and simple names ========================================================== 12:08:59 (1772471339) [ 1096.723533] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1096.755132] alg: No test for adler32 (adler32-zlib) [ 1098.275219] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 1098.710553] LNet: Added LNI 192.168.202.57@tcp [8/256/0/180] [ 1100.567256] Key type lgssc registered [ 1102.557848] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1164.036947] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1169.019899] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1194.198669] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1197.207732] Lustre: Mounted lustre-client [ 1215.184802] Lustre: DEBUG MARKER: == conf-sanity test 123ab: llog_print params output values from set_param -P ========================================================== 12:11:01 (1772471461) [ 1228.820826] Lustre: DEBUG MARKER: == conf-sanity test 123ac: llog_print with --start and --end ========================================================== 12:11:15 (1772471475) [ 1242.109168] Lustre: DEBUG MARKER: == conf-sanity test 123ad: llog_print shows all records == 12:11:28 (1772471488) [ 1258.087795] Lustre: DEBUG MARKER: == conf-sanity test 123ae: llog_cancel can cancel requested record ========================================================== 12:11:44 (1772471504) [ 1293.265548] Lustre: DEBUG MARKER: == conf-sanity test 123af: llog_catlist can show all config files correctly ========================================================== 12:12:18 (1772471538) [ 1312.284545] Lustre: DEBUG MARKER: == conf-sanity test 123ag: llog_print skips values deleted by set_param -P -d ========================================================== 12:12:38 (1772471558) [ 1332.992471] Lustre: DEBUG MARKER: == conf-sanity test 123ah: del_ost cancels config log entries correctly ========================================================== 12:12:59 (1772471579) [ 1346.469941] LustreError: 29407:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c25c7ca8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1346.553291] Lustre: Unmounted lustre-client [ 1347.087889] Lustre: Mounted lustre-client [ 1349.394459] Lustre: Unmounted lustre-client [ 1381.605778] Key type lgssc unregistered [ 1381.976507] LNet: 29880:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1381.999836] LNetError: 29880:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1382.042116] LNet: Removed LNI 192.168.202.57@tcp [ 1382.867181] Key type .llcrypt unregistered [ 1382.878359] Key type ._llcrypt unregistered [ 1419.681365] Key type ._llcrypt registered [ 1419.700355] Key type .llcrypt registered [ 1420.628400] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1420.654602] alg: No test for adler32 (adler32-zlib) [ 1421.981718] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 1422.333191] LNet: Added LNI 192.168.202.57@tcp [8/256/0/180] [ 1424.221253] Key type lgssc registered [ 1426.416852] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1529.412534] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1534.235597] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1557.905595] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1617.915463] Lustre: DEBUG MARKER: == conf-sanity test 123ai: llog_print display all non skipped records ========================================================== 12:17:44 (1772471864) [ 1657.421254] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1662.304536] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1685.425656] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1687.171744] Lustre: Mounted lustre-client [ 1838.847386] Lustre: DEBUG MARKER: == conf-sanity test 123aj: check permanent TBF rules ===== 12:21:25 (1772472085) [ 1894.127823] Lustre: DEBUG MARKER: == conf-sanity test 123F: clear and reset all parameters using set_param -F ========================================================== 12:22:20 (1772472140) [ 1907.686917] LustreError: 38770:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c25c6b1f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1907.825311] Lustre: Unmounted lustre-client [ 1998.378833] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2003.299945] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2029.743059] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2031.064092] Lustre: Mounted lustre-client [ 2038.651189] LustreError: 40563:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c25c6897000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2038.704795] Lustre: Unmounted lustre-client [ 2068.325625] Key type lgssc unregistered [ 2068.730310] LNet: 40966:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2068.743905] LNetError: 40966:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2068.775215] LNet: Removed LNI 192.168.202.57@tcp [ 2069.504107] Key type .llcrypt unregistered [ 2069.506054] Key type ._llcrypt unregistered [ 2092.510775] Key type ._llcrypt registered [ 2092.514359] Key type .llcrypt registered [ 2094.306426] Lustre: DEBUG MARKER: == conf-sanity test 123G: clear and reset all parameters using apply_yaml ========================================================== 12:25:40 (1772472340) [ 2097.845467] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2097.887262] alg: No test for adler32 (adler32-zlib) [ 2099.089642] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 2099.430694] LNet: Added LNI 192.168.202.57@tcp [8/256/0/180] [ 2101.133964] Key type lgssc registered [ 2102.390392] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2156.076821] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2160.433605] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2185.388565] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2188.441937] Lustre: Mounted lustre-client [ 2208.037157] LustreError: 44147:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c25c6334000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2208.140569] Lustre: Unmounted lustre-client [ 2301.881431] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2306.469389] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2330.733051] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2331.754969] Lustre: Mounted lustre-client [ 2338.880565] LustreError: 45947:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c25c6d42000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2339.027744] Lustre: Unmounted lustre-client [ 2369.642756] Key type lgssc unregistered [ 2370.027706] LNet: 46351:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2370.039792] LNetError: 46351:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2370.064413] LNet: Removed LNI 192.168.202.57@tcp [ 2370.689474] Key type .llcrypt unregistered [ 2370.691815] Key type ._llcrypt unregistered [ 2389.251799] Key type ._llcrypt registered [ 2389.257239] Key type .llcrypt registered [ 2390.696273] Lustre: DEBUG MARKER: == conf-sanity test 123H: check concurent accesses with 'lctl llog_print ========================================================== 12:30:37 (1772472637) [ 2394.186421] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2394.216956] alg: No test for adler32 (adler32-zlib) [ 2395.353478] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 2395.588088] LNet: Added LNI 192.168.202.57@tcp [8/256/0/180] [ 2397.335289] Key type lgssc registered [ 2398.958458] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2456.826848] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2461.204496] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2481.130308] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2484.050481] Lustre: Mounted lustre-client [ 2613.307574] Lustre: DEBUG MARKER: == conf-sanity test 124: check failover after replace_nids ========================================================== 12:34:19 (1772472859) [ 2616.090486] Lustre: DEBUG MARKER: SKIP: conf-sanity test_124 needs MDT failover setup [ 2619.066433] Lustre: DEBUG MARKER: == conf-sanity test 126: mount in parallel shouldn't cause a crash ========================================================== 12:34:25 (1772472865) [ 2622.751337] LustreError: 51218:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c25c6330800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2622.838732] Lustre: Unmounted lustre-client [ 2654.015440] Key type lgssc unregistered [ 2654.299537] LNet: 51622:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2654.312373] LNetError: 51622:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2654.338976] LNet: Removed LNI 192.168.202.57@tcp [ 2655.162171] Key type .llcrypt unregistered [ 2655.167904] Key type ._llcrypt unregistered [ 2693.838231] Key type ._llcrypt registered [ 2693.851191] Key type .llcrypt registered [ 2694.342220] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2694.353261] alg: No test for adler32 (adler32-zlib) [ 2695.507488] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 2695.778671] LNet: Added LNI 192.168.202.57@tcp [8/256/0/180] [ 2697.543201] Key type lgssc registered [ 2699.785822] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2741.423403] Lustre: DEBUG MARKER: == conf-sanity test 127: direct io overwrite on full ost ========================================================== 12:36:28 (1772472988) [ 2757.822582] Key type lgssc unregistered [ 2758.134957] LNet: 54045:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2758.149994] LNetError: 54045:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2758.167992] LNet: Removed LNI 192.168.202.57@tcp [ 2759.153838] Key type .llcrypt unregistered [ 2759.155852] Key type ._llcrypt unregistered [ 2774.300956] Key type ._llcrypt registered [ 2774.311374] Key type .llcrypt registered [ 2775.084964] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2775.104742] alg: No test for adler32 (adler32-zlib) [ 2776.158467] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 2776.377786] LNet: Added LNI 192.168.202.57@tcp [8/256/0/180] [ 2778.143144] Key type lgssc registered [ 2779.703587] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2833.937706] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2838.263456] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2865.317396] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2867.508095] Lustre: Mounted lustre-client [ 2930.552506] Lustre: DEBUG MARKER: == conf-sanity test 128: Force using remote logs with --nolocallogs ========================================================== 12:39:37 (1772473177) [ 2933.037141] Lustre: DEBUG MARKER: SKIP: conf-sanity test_128 need separate mgs device [ 2935.542059] Lustre: DEBUG MARKER: == conf-sanity test 129: attempt to connect an OST with the same index should fail ========================================================== 12:39:41 (1772473181) [ 2939.081516] LustreError: 57665:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c25c4bb6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2939.187831] Lustre: Unmounted lustre-client [ 3014.955928] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3019.118158] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3116.141791] Lustre: DEBUG MARKER: == conf-sanity test 130: re-register an MDT after writeconf ========================================================== 12:42:42 (1772473362) [ 3212.338993] Lustre: Mounted lustre-client [ 3218.076499] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3237.855630] Lustre: lustre-OST0000-osc-ffff9c25c7558800: disconnect after 23s idle [ 3237.874833] Lustre: lustre-MDT0001-mdc-ffff9c25c7558800: Connection to lustre-MDT0001 (at 192.168.202.157@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3256.442447] LustreError: 62615:0:(obd_config.c:2136:class_config_llog_handler()) MGC192.168.202.157@tcp: cfg command failed: rc = -17 [ 3256.448073] Lustre: cmd=cf001 0:lustre-MDT0001-mdc 1:mdc 2:lustre-clilmv_UUID [ 3256.448073] [ 3256.474854] LustreError: 61853:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [ 3270.512108] Lustre: DEBUG MARKER: == conf-sanity test 131: MDT backup restore with project ID ========================================================== 12:45:17 (1772473517) [ 3327.258319] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3345.379672] Lustre: lustre-OST0000-osc-ffff9c25c7558800: disconnect after 21s idle [ 3345.390465] Lustre: Skipped 1 previous similar message [ 3409.158275] LustreError: 66482:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c25c7558800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3409.622458] Lustre: Unmounted lustre-client [ 3548.149368] Lustre: Mounted lustre-client [ 3552.372595] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3599.743798] Lustre: DEBUG MARKER: == conf-sanity test 132: hsm_actions processed after failover ========================================================== 12:50:46 (1772473846) [ 3600.981582] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3601.584256] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 3601.592643] Lustre: Skipped 2 previous similar messages [ 3625.580788] Lustre: Unmounted lustre-client [ 3734.165232] Lustre: DEBUG MARKER: == conf-sanity test 133: stripe QOS: free space balance in a pool ========================================================== 12:53:01 (1772473981) [ 3735.115327] Lustre: DEBUG MARKER: SKIP: conf-sanity test_133 needs >= 4 OSTs [ 3736.390406] Lustre: DEBUG MARKER: == conf-sanity test 134: check_iam works without faults == 12:53:03 (1772473983) [ 3799.406293] Lustre: Mounted lustre-client [ 3803.287766] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 4255.178217] Lustre: DEBUG MARKER: == conf-sanity test 135: check the behavior when changelog is wrapped around ========================================================== 13:01:42 (1772474502) [ 4256.736688] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4267.101167] Lustre: Unmounted lustre-client [ 4348.001699] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4350.950648] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4367.083957] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4367.587145] Lustre: Mounted lustre-client [ 5403.490385] Lustre: 94014:0:(llog_cat.c:971:llog_cat_process_or_fork()) lustre-MDT0000-mdc-ffff9c25c68ad800: catlog [0xa:0x5:0x0] crosses index zero [ 5419.224660] LustreError: 101662:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c25c68ad800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5419.229172] LustreError: 101662:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 5419.258165] Lustre: Unmounted lustre-client [ 5445.735489] Key type lgssc unregistered [ 5445.864526] LNet: 102064:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5445.867959] LNetError: 102064:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5445.881892] LNet: Removed LNI 192.168.202.57@tcp [ 5446.139178] Key type .llcrypt unregistered [ 5446.140669] Key type ._llcrypt unregistered [ 5450.274301] Key type ._llcrypt registered [ 5450.275992] Key type .llcrypt registered [ 5450.913478] Lustre: DEBUG MARKER: == conf-sanity test 136: don't panic with bad obdecho setup ========================================================== 13:21:38 (1772475698) [ 5457.747518] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5457.756035] alg: No test for adler32 (adler32-zlib) [ 5458.669512] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 5458.765756] LNet: Added LNI 192.168.202.57@tcp [8/256/0/180] [ 5460.367239] Key type lgssc registered [ 5460.949863] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5498.504049] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5500.111237] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5510.370628] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5512.625298] Lustre: Mounted lustre-client [ 5519.200109] LustreError: 106446:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c25c7986800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5519.239212] Lustre: Unmounted lustre-client [ 5583.095556] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5584.578921] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5593.682223] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5614.989972] Lustre: DEBUG MARKER: == conf-sanity test 137: a new MDT should inherit pools, parameters and failnode ========================================================== 13:24:22 (1772475862) [ 5615.649610] Lustre: DEBUG MARKER: SKIP: conf-sanity test_137 needs >= 3 MDTs [ 5616.490502] Lustre: DEBUG MARKER: == conf-sanity test 140: remove_updatelog script actions ========================================================== 13:24:24 (1772475864) [ 5628.672347] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5630.072186] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5637.544593] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5648.373871] Lustre: Mounted lustre-client [ 5653.474283] Lustre: lustre-MDT0000-mdc-ffff9c25c68ac800: Connection to lustre-MDT0000 (at 192.168.202.157@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5663.716225] Lustre: lustre-MDT0001-mdc-ffff9c25c68ac800: Connection to lustre-MDT0001 (at 192.168.202.157@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5673.951168] Lustre: 102822:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772475906/real 1772475906] req@000000003dc22641 x1858575487019776/t0(0) o400->MGC192.168.202.157@tcp@192.168.202.157@tcp:26/25 lens 224/224 e 0 to 1 dl 1772475922 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 5673.960409] LustreError: MGC192.168.202.157@tcp: Connection to MGS (at 192.168.202.157@tcp) was lost; in progress operations using this service will fail [ 5793.762479] Lustre: Evicted from MGS (at 192.168.202.157@tcp) after server handle changed from 0x1b3800ebfde0c794 to 0x1b3800ebfde0de46 [ 5793.767614] Lustre: MGC192.168.202.157@tcp: Connection restored to 192.168.202.157@tcp (at 192.168.202.157@tcp) [ 5803.518866] LustreError: lustre-MDT0000-mdc-ffff9c25c68ac800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 5803.532761] Lustre: lustre-MDT0000-mdc-ffff9c25c68ac800: Connection restored to 192.168.202.157@tcp (at 192.168.202.157@tcp) [ 5806.440083] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5807.046663] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 5808.328943] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5808.610333] LustreError: lustre-MDT0001-mdc-ffff9c25c68ac800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 5808.616794] Lustre: lustre-MDT0001-mdc-ffff9c25c68ac800: Connection restored to 192.168.202.157@tcp (at 192.168.202.157@tcp) [ 5809.927617] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 1 sec [ 5814.475724] LustreError: 113389:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c25c68ac800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5814.520517] Lustre: Unmounted lustre-client [ 5856.897434] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5858.101652] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5865.390938] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5895.340904] Lustre: DEBUG MARKER: == conf-sanity test 150: test setting max_cached_mb to a % ========================================================== 13:29:03 (1772476143) [ 5905.479265] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5906.860946] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5913.662521] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5924.336797] Lustre: Mounted lustre-client [ 5925.803451] LustreError: 119032:0:(lproc_llite.c:615:ll_max_cached_mb_seq_write()) lustre: cannot set max_cached_mb=0 MB more than 3693 MB: rc = -34 [ 5928.550134] Lustre: DEBUG MARKER: == conf-sanity test 151a: damaged local config doesn't prevent mounting ========================================================== 13:29:36 (1772476176) [ 5928.885605] LustreError: 119635:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c25c6b1b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5928.924344] Lustre: Unmounted lustre-client [ 5954.764710] Key type lgssc unregistered [ 5954.880569] LNet: 120037:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5954.884161] LNetError: 120037:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5954.893256] LNet: Removed LNI 192.168.202.57@tcp [ 5955.125987] Key type .llcrypt unregistered [ 5955.127084] Key type ._llcrypt unregistered [ 5959.593185] Key type ._llcrypt registered [ 5959.594529] Key type .llcrypt registered [ 5959.819493] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5959.827920] alg: No test for adler32 (adler32-zlib) [ 5960.701247] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 5960.793749] LNet: Added LNI 192.168.202.57@tcp [8/256/0/180] [ 5962.391109] Key type lgssc registered [ 5962.818091] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6008.829369] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6010.061104] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6015.930993] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6064.956522] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6066.214606] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6074.208994] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6096.107886] Lustre: DEBUG MARKER: == conf-sanity test 151b: -ENOSPC doesn't affect mount === 13:32:23 (1772476343) [ 6100.195582] Key type lgssc unregistered [ 6100.311485] LNet: 127042:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6100.313766] LNetError: 127042:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6100.320494] LNet: Removed LNI 192.168.202.57@tcp [ 6100.560482] Key type .llcrypt unregistered [ 6100.561555] Key type ._llcrypt unregistered [ 6104.702446] Key type ._llcrypt registered [ 6104.706045] Key type .llcrypt registered [ 6104.872390] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6104.884865] alg: No test for adler32 (adler32-zlib) [ 6105.760531] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 6105.855371] LNet: Added LNI 192.168.202.57@tcp [8/256/0/180] [ 6107.447188] Key type lgssc registered [ 6107.876065] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6125.810580] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6127.009258] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6133.342800] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6182.548405] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6183.858907] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6192.080357] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6213.770985] Lustre: DEBUG MARKER: == conf-sanity test 152: seq allocation error in OSP ===== 13:34:21 (1772476461) [ 6243.970299] Lustre: Mounted lustre-client [ 6246.408968] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 6255.792495] Lustre: DEBUG MARKER: ADD OST3 [ 6262.728616] Lustre: DEBUG MARKER: STOP OST3 [ 6268.277115] Lustre: DEBUG MARKER: START OST3 again [ 6268.383363] Lustre: lustre-OST0000-osc-ffff9c25ca22c000: disconnect after 23s idle [ 6268.389271] Lustre: lustre-OST0003-osc-ffff9c25ca22c000: Connection to lustre-OST0003 (at 192.168.202.157@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6272.513056] Lustre: lustre-OST0003-osc-ffff9c25ca22c000: Connection restored to 192.168.202.157@tcp (at 192.168.202.157@tcp) [ 6288.608343] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6288.614321] LustreError: 137552:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff9c25ca22c000: inode [0x240000bd0:0x1:0x0] mdc close failed: rc = -108 [ 6298.878181] Lustre: Unmounted lustre-client [ 6351.790904] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6353.054908] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6360.428734] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6387.695236] Lustre: DEBUG MARKER: == conf-sanity test 153a: bypass invalid NIDs quickly ==== 13:37:15 (1772476635) [ 6417.043294] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6418.181966] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6425.684512] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6453.746064] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6454.872927] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6460.787244] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6461.519058] LNetError: 127452:0:(lib-move.c:2321:lnet_handle_find_routed_path()) peer 192.168.252.112@tcp2 has no available nets [ 6472.175130] Lustre: Mounted lustre-client [ 6476.738125] LustreError: 145834:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c25c5dff000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6476.771992] Lustre: Unmounted lustre-client [ 6494.293669] Key type lgssc unregistered [ 6515.487250] LNet: 1 local NIs in recovery (showing 1): 192.168.202.57@tcp [ 6522.079451] LNet: 146241:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6522.082309] LNetError: 146241:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6591.717814] LNet: Removed LNI 192.168.202.57@tcp [ 6591.972285] Key type .llcrypt unregistered [ 6591.973944] Key type ._llcrypt unregistered [ 6597.171130] Key type ._llcrypt registered [ 6597.172368] Key type .llcrypt registered [ 6597.688277] Lustre: DEBUG MARKER: == conf-sanity test 153b: added IPv6 NID support ========= 13:40:45 (1772476845) [ 6603.401024] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6603.408526] alg: No test for adler32 (adler32-zlib) [ 6604.270383] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 6604.352866] LNet: Added LNI 192.168.202.57@tcp [8/256/0/180] [ 6605.935227] Key type lgssc registered [ 6606.343940] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6635.394606] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6636.793219] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6645.133253] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6689.752627] Lustre: Mounted lustre-client [ 6692.254449] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 6703.002807] LustreError: 153021:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c25c8595000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6703.033123] Lustre: Unmounted lustre-client [ 6703.059080] LNetError: 147423:0:(lib-socket.c:313:lnet_sock_create()) Error trying to bind to fe80::54c5:9bff:fed0:b011/1023: rc = -22 [ 6703.062073] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 6703.117903] Lustre: Mounted lustre-client [ 6703.553848] LustreError: 153172:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c25ca22a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6703.557356] LustreError: 153172:0:(lov_obd.c:783:lov_cleanup()) Skipped 7 previous similar messages [ 6703.589517] Lustre: Unmounted lustre-client [ 6703.590416] Lustre: Skipped 3 previous similar messages [ 6704.178762] LNetError: 147423:0:(lib-socket.c:313:lnet_sock_create()) Error trying to bind to fe80::54c5:9bff:fed0:b011/1023: rc = -22 [ 6704.182425] Lustre: 153327:0:(obd_mount.c:255:lustre_add_mgc_failnodes()) MGC6699:7654::1234:1234:d84@tcp: can't add failover NID 5031:db8:85a3:8d3:1319:8a2e:370:7348@tcp, rc = -75 [ 6704.183527] LNetError: Unexpected error -22 connecting to 2001:db8:85a3::8a2e:370:7334@tcp at host [2001:db8:85a3::8a2e:370:7334]:988 [ 6704.187247] LNetError: 147423:0:(lib-socket.c:313:lnet_sock_create()) Skipped 1 previous similar message [ 6704.193697] LNetError: Skipped 1 previous similar message [ 6704.249439] Lustre: Mounted lustre-client [ 6704.250780] Lustre: Skipped 7 previous similar messages [ 6706.625327] Lustre: DEBUG MARKER: == conf-sanity test 153c: don't stuck on unreached NID === 13:42:34 (1772476954) [ 6764.127357] LNet: 1 local NIs in recovery (showing 1): 192.168.202.57@tcp [ 6765.627840] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6766.863313] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6774.505975] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6804.061745] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6805.310315] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6811.598954] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6816.863386] LustreError: 158765:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.252.112@tcp: failed processing log, type 1: rc = -110 [ 6864.479289] LNet: 1 local NIs in recovery (showing 1): 192.168.202.57@tcp [ 6924.896309] LNet: 1 local NIs in recovery (showing 1): 192.168.202.57@tcp [ 6954.975336] LustreError: MGC192.168.252.112@tcp: Confguration from log lustre-client failed from MGS -110. Communication error between node & MGS, a bad configuration, or other errors. See syslog for more info [ 6954.981465] Lustre: Unmounted lustre-client [ 6954.984595] Lustre: Skipped 6 previous similar messages [ 6954.989309] LustreError: 158765:0:(super25.c:186:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 6980.718751] Key type lgssc unregistered [ 6999.647193] LNet: 1 local NIs in recovery (showing 1): 192.168.202.57@tcp [ 7005.407435] LNet: 159221:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7005.410855] LNetError: 159221:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7075.045461] LNet: Removed LNI 192.168.202.57@tcp [ 7075.305129] Key type .llcrypt unregistered [ 7075.306923] Key type ._llcrypt unregistered [ 7080.533261] Key type ._llcrypt registered [ 7080.535409] Key type .llcrypt registered [ 7081.009075] Lustre: DEBUG MARKER: == conf-sanity test 154: expand .. on rename after MDT backup restore ========================================================== 13:48:48 (1772477328) [ 7086.804474] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7086.841172] alg: No test for adler32 (adler32-zlib) [ 7087.715508] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 7087.792269] LNet: Added LNI 192.168.202.57@tcp [8/256/0/180] [ 7089.375171] Key type lgssc registered [ 7089.763470] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7137.842235] Lustre: Mounted lustre-client [ 7140.140583] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 7150.920533] LustreError: 164699:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c25c9f07800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7150.997159] Lustre: Unmounted lustre-client [ 7220.251609] Lustre: Mounted lustre-client [ 7222.587502] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 7229.181393] LustreError: 167704:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c25c8f7f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7229.185368] LustreError: 167704:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 7229.213165] Lustre: Unmounted lustre-client [ 7265.767985] Lustre: DEBUG MARKER: == conf-sanity test 155: gap in seq allocation from ofd after restarting ========================================================== 13:51:53 (1772477513) [ 7294.399570] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7295.589915] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7302.700991] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7346.334501] Lustre: Mounted lustre-client [ 7348.634932] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 7357.382480] LustreError: 174454:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c25c3271000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7357.386047] LustreError: 174454:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 7357.421115] Lustre: Unmounted lustre-client [ 7412.361367] Lustre: Mounted lustre-client [ 7414.649397] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 7425.473841] Lustre: DEBUG MARKER: == conf-sanity test 156: root_fid on export consistent with client mount ========================================================== 13:54:33 (1772477673) [ 7425.732744] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7436.025911] Lustre: Unmounted lustre-client [ 7507.381732] Lustre: Mounted lustre-client [ 7509.535903] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 7516.505428] LustreError: 181719:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c25c5ca8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7516.508768] LustreError: 181719:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 7516.537566] Lustre: Unmounted lustre-client [ 7516.673536] Lustre: Mounted lustre-client [ 7519.399489] Lustre: DEBUG MARKER: == conf-sanity test 160: MGC updates failnodes from all participants ========================================================== 13:56:07 (1772477767) [ 7580.401047] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7581.440472] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7588.110238] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7608.411609] Key type lgssc unregistered [ 7608.531618] LNet: 186707:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7608.534821] LNetError: 186707:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7608.544771] LNet: Removed LNI 192.168.202.57@tcp [ 7608.785133] Key type .llcrypt unregistered [ 7608.786432] Key type ._llcrypt unregistered [ 7728.632731] Key type ._llcrypt registered [ 7728.634328] Key type .llcrypt registered [ 7728.810085] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7728.816457] alg: No test for adler32 (adler32-zlib) [ 7729.678239] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 7729.765661] LNet: Added LNI 192.168.202.57@tcp [8/256/0/180] [ 7731.359148] Key type lgssc registered [ 7731.767533] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7747.682966] Lustre: DEBUG MARKER: == conf-sanity test 161: test '-o mgsname' option ======== 13:59:55 (1772477995) [ 7760.383586] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7761.529260] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7768.580495] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7775.191413] Lustre: Mounted lustre-client [ 7776.524738] LustreError: 190647:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c25c5ca9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7776.557152] Lustre: Unmounted lustre-client [ 7776.646456] Lustre: Mounted lustre-client [ 7777.154094] LustreError: 190779:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c25c5dfe000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7777.157880] LustreError: 190779:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 7777.182042] Lustre: Unmounted lustre-client [ 7777.183288] Lustre: Skipped 1 previous similar message [ 7794.148279] Key type lgssc unregistered [ 7794.264460] LNet: 191182:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7794.267383] LNetError: 191182:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7794.275952] LNet: Removed LNI 192.168.202.57@tcp [ 7794.515273] Key type .llcrypt unregistered [ 7794.516371] Key type ._llcrypt unregistered [ 7799.231989] Key type ._llcrypt registered [ 7799.232992] Key type .llcrypt registered [ 7799.684066] Lustre: DEBUG MARKER: == conf-sanity test 162: replace nids with -o noclient === 14:00:47 (1772478047) [ 7800.693040] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7800.710821] alg: No test for adler32 (adler32-zlib) [ 7801.559637] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 7801.636906] LNet: Added LNI 192.168.202.57@tcp [8/256/0/180] [ 7803.215159] Key type lgssc registered [ 7803.611444] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7819.705747] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7820.899870] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7826.795575] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7842.287844] Lustre: Mounted lustre-client [ 7844.992801] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7845.555174] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7847.394790] Lustre: lustre-MDT0000-mdc-ffff9c25c6334800: Connection to lustre-MDT0000 (at 192.168.202.157@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7852.513917] Lustre: lustre-MDT0001-mdc-ffff9c25c6334800: Connection to lustre-MDT0001 (at 192.168.202.157@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7867.871255] Lustre: 192277:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772478100/real 1772478100] req@00000000ad9cb8b7 x1858577943762048/t0(0) o400->MGC192.168.202.157@tcp@192.168.202.157@tcp:26/25 lens 224/224 e 0 to 1 dl 1772478116 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 7867.873431] LustreError: MGC192.168.202.157@tcp: Connection to MGS (at 192.168.202.157@tcp) was lost; in progress operations using this service will fail [ 7872.993413] Lustre: Evicted from MGS (at 192.168.202.157@tcp) after server handle changed from 0xde895a4240f85555 to 0xde895a4240f85700 [ 7872.996208] Lustre: MGC192.168.202.157@tcp: Connection restored to 192.168.202.157@tcp (at 192.168.202.157@tcp) [ 7883.234720] LustreError: lustre-MDT0000-mdc-ffff9c25c6334800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 7883.242955] Lustre: lustre-MDT0000-mdc-ffff9c25c6334800: Connection restored to 192.168.202.157@tcp (at 192.168.202.157@tcp) [ 7885.156740] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7885.656047] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in IDLE state after 0 sec [ 7886.764717] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7887.266882] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7888.679492] LustreError: 196587:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c25c6334800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7888.707051] Lustre: Unmounted lustre-client [ 7905.699631] Key type lgssc unregistered [ 7905.810430] LNet: 196990:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7905.812595] LNetError: 196990:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7905.822404] LNet: Removed LNI 192.168.202.57@tcp [ 7906.045289] Key type .llcrypt unregistered [ 7906.046495] Key type ._llcrypt unregistered [ 7915.256802] Key type ._llcrypt registered [ 7915.258150] Key type .llcrypt registered [ 7915.409550] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7915.415267] alg: No test for adler32 (adler32-zlib) [ 7916.269565] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 7916.360327] LNet: Added LNI 192.168.202.57@tcp [8/256/0/180] [ 7917.943181] Key type lgssc registered [ 7918.322976] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7946.082834] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7947.241339] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7954.514055] Lustre: DEBUG MARKER: oleg257-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7984.854555] Lustre: DEBUG MARKER: == conf-sanity test 200a: check CPU partitions =========== 14:03:52 (1772478232) [ 7989.328696] Key type lgssc unregistered [ 7989.445388] LNet: 201891:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7989.448112] LNetError: 201891:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7989.456661] LNet: Removed LNI 192.168.202.57@tcp [ 7989.673353] Key type .llcrypt unregistered [ 7989.674641] Key type ._llcrypt unregistered [ 7989.976063] Key type ._llcrypt registered [ 7989.977637] Key type .llcrypt registered [ 7990.136058] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 4 [ 7990.141906] alg: No test for adler32 (adler32-zlib) [ 7990.994755] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 7991.076131] LNet: Added LNI 192.168.202.57@tcp [8/256/0/180] [ 7992.663185] Key type lgssc registered [ 7993.052878] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8000.649348] Key type lgssc unregistered [ 8000.771288] LNet: 203528:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8000.775278] LNetError: 203528:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8000.787551] LNet: Removed LNI 192.168.202.57@tcp [ 8001.020523] Key type .llcrypt unregistered [ 8001.021550] Key type ._llcrypt unregistered [ 8001.144463] Key type ._llcrypt registered [ 8001.145512] Key type .llcrypt registered [ 8001.591894] Lustre: DEBUG MARKER: == conf-sanity test 200b: set CPU pattern using core selection ========================================================== 14:04:09 (1772478249) [ 8005.484253] Key type .llcrypt unregistered [ 8005.485526] Key type ._llcrypt unregistered [ 8005.803349] Key type ._llcrypt registered [ 8005.815056] Key type .llcrypt registered [ 8005.967040] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8005.971860] alg: No test for adler32 (adler32-zlib) [ 8006.827459] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 8006.913422] LNet: Added LNI 192.168.202.57@tcp [8/256/0/180] [ 8008.495134] Key type lgssc registered [ 8008.921775] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8014.166318] Key type lgssc unregistered [ 8014.284474] LNet: 205079:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8014.287279] LNetError: 205079:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8014.295463] LNet: Removed LNI 192.168.202.57@tcp [ 8014.514985] Key type .llcrypt unregistered [ 8014.516543] Key type ._llcrypt unregistered [ 8018.151770] Key type ._llcrypt registered [ 8018.153536] Key type .llcrypt registered [ 8018.346269] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8018.357506] alg: No test for adler32 (adler32-zlib) [ 8019.220267] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 8019.302449] LNet: Added LNI 192.168.202.57@tcp [8/256/0/180] [ 8020.887168] Key type lgssc registered [ 8021.300707] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8028.859519] Key type lgssc unregistered [ 8028.974394] LNet: 206841:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8028.977252] LNetError: 206841:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8028.990457] LNet: Removed LNI 192.168.202.57@tcp [ 8029.196351] Key type .llcrypt unregistered [ 8029.197263] Key type ._llcrypt unregistered [ 8029.323776] Key type ._llcrypt registered [ 8029.324911] Key type .llcrypt registered [ 8029.765697] Lustre: DEBUG MARKER: == conf-sanity test 200c: set CPU pattern using NUMA node layout ========================================================== 14:04:37 (1772478277) [ 8033.280280] Key type .llcrypt unregistered [ 8033.281621] Key type ._llcrypt unregistered [ 8033.565529] Key type ._llcrypt registered [ 8033.568517] Key type .llcrypt registered [ 8033.737430] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8033.743795] alg: No test for adler32 (adler32-zlib) [ 8034.605098] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 8034.683076] LNet: Added LNI 192.168.202.57@tcp [8/256/0/180] [ 8036.263124] Key type lgssc registered [ 8036.629760] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8041.438762] Key type lgssc unregistered [ 8041.548388] LNet: 208385:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8041.551589] LNetError: 208385:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8041.558288] LNet: Removed LNI 192.168.202.57@tcp [ 8041.778526] Key type .llcrypt unregistered [ 8041.780035] Key type ._llcrypt unregistered [ 8043.967794] Key type ._llcrypt registered [ 8043.968905] Key type .llcrypt registered [ 8044.198371] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8044.204688] alg: No test for adler32 (adler32-zlib) [ 8045.071020] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 8045.156231] LNet: Added LNI 192.168.202.57@tcp [8/256/0/180] [ 8046.743780] Key type lgssc registered [ 8047.216105] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8052.422490] Key type lgssc unregistered [ 8052.545416] LNet: 209689:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8052.547995] LNetError: 209689:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8052.556278] LNet: Removed LNI 192.168.202.57@tcp [ 8052.789452] Key type .llcrypt unregistered [ 8052.790494] Key type ._llcrypt unregistered [ 8055.042129] Key type ._llcrypt registered [ 8055.043350] Key type .llcrypt registered [ 8055.222305] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8055.234084] alg: No test for adler32 (adler32-zlib) [ 8056.103512] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 8056.180905] LNet: Added LNI 192.168.202.57@tcp [8/256/0/180] [ 8057.759142] Key type lgssc registered [ 8058.175756] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8063.637861] Key type lgssc unregistered [ 8063.757129] LNet: 210989:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8063.760732] LNetError: 210989:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8063.771301] LNet: Removed LNI 192.168.202.57@tcp [ 8063.991336] Key type .llcrypt unregistered [ 8063.992444] Key type ._llcrypt unregistered [ 8066.292264] Key type ._llcrypt registered [ 8066.293840] Key type .llcrypt registered [ 8066.474152] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8066.480491] alg: No test for adler32 (adler32-zlib) [ 8067.365296] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 8067.453772] LNet: Added LNI 192.168.202.57@tcp [8/256/0/180] [ 8069.039187] Key type lgssc registered [ 8069.434302] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8077.096751] Key type lgssc unregistered [ 8077.221401] LNet: 212763:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8077.225249] LNetError: 212763:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8077.234414] LNet: Removed LNI 192.168.202.57@tcp [ 8077.451514] Key type .llcrypt unregistered [ 8077.452796] Key type ._llcrypt unregistered [ 8077.590451] Key type ._llcrypt registered [ 8077.591641] Key type .llcrypt registered [ 8078.061804] Lustre: DEBUG MARKER: == conf-sanity test 200d: set CPU pattern to exclude only CPU 1 ========================================================== 14:05:25 (1772478325) [ 8082.032332] Key type .llcrypt unregistered [ 8082.033574] Key type ._llcrypt unregistered [ 8082.360178] Key type ._llcrypt registered [ 8082.361687] Key type .llcrypt registered [ 8082.536054] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8082.541511] alg: No test for adler32 (adler32-zlib) [ 8083.404638] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 8083.494373] LNet: Added LNI 192.168.202.57@tcp [8/256/0/180] [ 8085.087157] Key type lgssc registered [ 8085.511311] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8090.658822] Key type lgssc unregistered [ 8090.778453] LNet: 214312:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8090.781899] LNetError: 214312:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8090.794337] LNet: Removed LNI 192.168.202.57@tcp [ 8091.004561] Key type .llcrypt unregistered [ 8091.005639] Key type ._llcrypt unregistered [ 8093.114395] Key type ._llcrypt registered [ 8093.115867] Key type .llcrypt registered [ 8093.287937] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8093.292288] alg: No test for adler32 (adler32-zlib) [ 8094.142147] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 8094.219224] LNet: Added LNI 192.168.202.57@tcp [8/256/0/180] [ 8095.807124] Key type lgssc registered [ 8096.207543] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8101.363431] Key type lgssc unregistered [ 8101.484351] LNet: 215622:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8101.487539] LNetError: 215622:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8101.496429] LNet: Removed LNI 192.168.202.57@tcp [ 8101.716344] Key type .llcrypt unregistered [ 8101.717451] Key type ._llcrypt unregistered [ 8103.859443] Key type ._llcrypt registered [ 8103.864058] Key type .llcrypt registered [ 8104.083468] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8104.088495] alg: No test for adler32 (adler32-zlib) [ 8104.950850] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 8105.037917] LNet: Added LNI 192.168.202.57@tcp [8/256/0/180] [ 8106.623152] Key type lgssc registered [ 8107.005415] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8111.946368] Key type lgssc unregistered [ 8112.072424] LNet: 216926:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8112.074764] LNetError: 216926:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8112.081335] LNet: Removed LNI 192.168.202.57@tcp [ 8112.293422] Key type .llcrypt unregistered [ 8112.294722] Key type ._llcrypt unregistered [ 8114.412716] Key type ._llcrypt registered [ 8114.414257] Key type .llcrypt registered [ 8114.576771] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8114.582607] alg: No test for adler32 (adler32-zlib) [ 8115.435810] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 8115.517081] LNet: Added LNI 192.168.202.57@tcp [8/256/0/180] [ 8117.103152] Key type lgssc registered [ 8117.484779] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8122.321824] Key type lgssc unregistered [ 8122.427321] LNet: 218230:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8122.429504] LNetError: 218230:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8122.437286] LNet: Removed LNI 192.168.202.57@tcp [ 8122.659492] Key type .llcrypt unregistered [ 8122.660689] Key type ._llcrypt unregistered [ 8124.989714] Key type ._llcrypt registered [ 8124.990968] Key type .llcrypt registered [ 8125.160169] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8125.164658] alg: No test for adler32 (adler32-zlib) [ 8126.022864] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 8126.100465] LNet: Added LNI 192.168.202.57@tcp [8/256/0/180] [ 8127.679100] Key type lgssc registered [ 8128.061440] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8133.092624] Key type lgssc unregistered [ 8133.196285] LNet: 219537:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8133.198594] LNetError: 219537:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8133.205276] LNet: Removed LNI 192.168.202.57@tcp [ 8133.393306] Key type .llcrypt unregistered [ 8133.394475] Key type ._llcrypt unregistered [ 8135.438897] Key type ._llcrypt registered [ 8135.440762] Key type .llcrypt registered [ 8135.598225] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8135.602756] alg: No test for adler32 (adler32-zlib) [ 8136.453615] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 8136.530565] LNet: Added LNI 192.168.202.57@tcp [8/256/0/180] [ 8138.111183] Key type lgssc registered [ 8138.525060] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8143.399206] Key type lgssc unregistered [ 8143.513238] LNet: 220838:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8143.515289] LNetError: 220838:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8143.521257] LNet: Removed LNI 192.168.202.57@tcp [ 8143.755403] Key type .llcrypt unregistered [ 8143.756425] Key type ._llcrypt unregistered [ 8151.159050] Key type ._llcrypt registered [ 8151.160356] Key type .llcrypt registered [ 8151.610373] Lustre: DEBUG MARKER: == conf-sanity test 200e: set CPU pattern using relative core exclusion ========================================================== 14:06:39 (1772478399) [ 8155.365369] Key type .llcrypt unregistered [ 8155.366940] Key type ._llcrypt unregistered [ 8155.691078] Key type ._llcrypt registered [ 8155.695689] Key type .llcrypt registered [ 8155.907201] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8155.917168] alg: No test for adler32 (adler32-zlib) [ 8156.782928] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 8156.865297] LNet: Added LNI 192.168.202.57@tcp [8/256/0/180] [ 8158.455142] Key type lgssc registered [ 8158.812215] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8163.515855] Key type lgssc unregistered [ 8163.627337] LNet: 223163:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8163.630370] LNetError: 223163:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8163.638248] LNet: Removed LNI 192.168.202.57@tcp [ 8163.840344] Key type .llcrypt unregistered [ 8163.841595] Key type ._llcrypt unregistered [ 8165.884078] Key type ._llcrypt registered [ 8165.884994] Key type .llcrypt registered [ 8166.037209] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8166.042426] alg: No test for adler32 (adler32-zlib) [ 8166.895144] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 8166.971072] LNet: Added LNI 192.168.202.57@tcp [8/256/0/180] [ 8168.551111] Key type lgssc registered [ 8168.912176] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8173.933393] Key type lgssc unregistered [ 8174.051324] LNet: 224479:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8174.054756] LNetError: 224479:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8174.061254] LNet: Removed LNI 192.168.202.57@tcp [ 8174.274238] Key type .llcrypt unregistered [ 8174.275487] Key type ._llcrypt unregistered [ 8176.357602] Key type ._llcrypt registered [ 8176.358719] Key type .llcrypt registered [ 8176.528389] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8176.532838] alg: No test for adler32 (adler32-zlib) [ 8177.394994] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 8177.478488] LNet: Added LNI 192.168.202.57@tcp [8/256/0/180] [ 8179.055159] Key type lgssc registered [ 8179.433347] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8184.423151] Key type lgssc unregistered [ 8184.535250] LNet: 225785:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8184.538379] LNetError: 225785:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8184.546264] LNet: Removed LNI 192.168.202.57@tcp [ 8184.768405] Key type .llcrypt unregistered [ 8184.769376] Key type ._llcrypt unregistered [ 8186.805340] Key type ._llcrypt registered [ 8186.806188] Key type .llcrypt registered [ 8186.975430] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8186.979808] alg: No test for adler32 (adler32-zlib) [ 8187.826648] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 8187.902642] LNet: Added LNI 192.168.202.57@tcp [8/256/0/180] [ 8189.479132] Key type lgssc registered [ 8189.836737] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8197.150609] Key type lgssc unregistered [ 8197.269580] LNet: 227577:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8197.271755] LNetError: 227577:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8197.282364] LNet: Removed LNI 192.168.202.57@tcp [ 8197.509743] Key type .llcrypt unregistered [ 8197.510742] Key type ._llcrypt unregistered [ 8197.637187] Key type ._llcrypt registered [ 8197.638161] Key type .llcrypt registered [ 8198.121593] Lustre: DEBUG MARKER: == conf-sanity test 802a: simulate readonly device ======= 14:07:25 (1772478445) [ 8198.649247] Lustre: DEBUG MARKER: SKIP: conf-sanity test_802a ZFS specific test [ 8204.426809] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8204.443071] alg: No test for adler32 (adler32-zlib) [ 8205.308944] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 8205.395385] LNet: Added LNI 192.168.202.57@tcp [8/256/0/180] [ 8206.983189] Key type lgssc registered [ 8207.370406] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8222.847432] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 7905 sec ========== 14:07:50 (1772478470) [ 8223.428496] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 14:07:51 (1772478471) === [ 8224.621827] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 14:07:52 (1772478472) === [ 8235.922360] Key type lgssc unregistered [ 8236.042876] LNet: 231317:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8236.046606] LNetError: 231317:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8236.058318] LNet: Removed LNI 192.168.202.57@tcp [ 8236.316825] Key type .llcrypt unregistered [ 8236.318623] Key type ._llcrypt unregistered