[ 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 3.0.0 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.16.3-2.fc40 04/01/2014 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 453016514 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 0x000f5410-0x000f541f] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F5220 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE1D6F 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE1C0B 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 001BCB (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE1C7F 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE1D0F 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE1D47 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe1c0b-0xbffe1c7e] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe1c0a] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe1c7f-0xbffe1d0e] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe1d0f-0xbffe1d46] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe1d47-0xbffe1d6e] [ 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, 524580K 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.001014] APIC: Switch to symmetric I/O mode setup [ 0.003166] x2apic enabled [ 0.004010] Switched APIC routing to physical x2apic. [ 0.005016] kvm-guest: setup PV IPIs [ 0.007887] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008022] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.010014] pid_max: default: 32768 minimum: 301 [ 0.011134] LSM: Security Framework initializing [ 0.012073] Yama: becoming mindful. [ 0.013034] SELinux: Initializing. [ 0.014056] *** VALIDATE selinux *** [ 0.022901] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.027258] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.029097] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030135] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031133] *** VALIDATE tmpfs *** [ 0.033072] *** VALIDATE proc *** [ 0.034261] *** VALIDATE cgroup *** [ 0.035009] *** VALIDATE cgroup2 *** [ 0.036267] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.037160] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.038009] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.039032] Spectre V2 : User space: Vulnerable [ 0.041006] Speculative Store Bypass: Vulnerable [ 0.044284] debug: unmapping init [mem 0xffffffffa3659000-0xffffffffa3660fff] [ 0.047189] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.048758] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.049024] ... version: 2 [ 0.050011] ... bit width: 48 [ 0.051013] ... generic registers: 4 [ 0.052009] ... value mask: 0000ffffffffffff [ 0.053012] ... max period: 00007fffffffffff [ 0.054019] ... fixed-purpose events: 3 [ 0.055012] ... event mask: 000000070000000f [ 0.056293] rcu: Hierarchical SRCU implementation. [ 0.058494] smp: Bringing up secondary CPUs ... [ 0.059656] x86: Booting SMP configuration: [ 0.060039] .... node #0, CPUs: #1 #2 #3 [ 0.070013] smp: Brought up 1 node, 4 CPUs [ 0.072018] smpboot: Max logical packages: 1 [ 0.073014] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.168332] node 0 deferred pages initialised in 93ms [ 0.171180] devtmpfs: initialized [ 0.172225] x86/mm: Memory block size: 128MB [ 0.174838] gcov: version magic: 0x41383552 [ 0.175646] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.180126] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.182483] pinctrl core: initialized pinctrl subsystem [ 0.184217] [ 0.184692] ************************************************************* [ 0.187026] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.190025] ** ** [ 0.193037] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.195019] ** ** [ 0.197023] ** This means that this kernel is built to expose internal ** [ 0.199020] ** IOMMU data structures, which may compromise security on ** [ 0.201018] ** your system. ** [ 0.203017] ** ** [ 0.205016] ** If you see this message and you are not debugging the ** [ 0.207027] ** kernel, report this immediately to your vendor! ** [ 0.210025] ** ** [ 0.212022] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.214025] ************************************************************* [ 0.217898] NET: Registered protocol family 16 [ 0.219529] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.222080] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.224098] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.228160] cpuidle: using governor menu [ 0.229695] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.232752] PCI: Using configuration type 1 for base access [ 0.235184] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.245160] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.247043] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.251181] cryptd: max_cpu_qlen set to 1000 [ 0.256008] ACPI: Added _OSI(Module Device) [ 0.258018] ACPI: Added _OSI(Processor Device) [ 0.259013] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.261015] ACPI: Added _OSI(Processor Aggregator Device) [ 0.265718] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.272502] ACPI: Interpreter enabled [ 0.274067] ACPI: PM: (supports S0 S3 S4 S5) [ 0.276014] ACPI: Using IOAPIC for interrupt routing [ 0.278166] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.281412] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.292116] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.294040] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.297022] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.300081] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.305383] acpiphp: Slot [2] registered [ 0.307186] acpiphp: Slot [5] registered [ 0.309145] acpiphp: Slot [6] registered [ 0.310128] acpiphp: Slot [3] registered [ 0.312376] acpiphp: Slot [4] registered [ 0.313092] acpiphp: Slot [7] registered [ 0.314097] acpiphp: Slot [8] registered [ 0.316094] acpiphp: Slot [9] registered [ 0.317096] acpiphp: Slot [10] registered [ 0.319114] acpiphp: Slot [11] registered [ 0.320113] acpiphp: Slot [12] registered [ 0.321000] acpiphp: Slot [13] registered [ 0.321000] acpiphp: Slot [14] registered [ 0.323110] acpiphp: Slot [15] registered [ 0.324168] acpiphp: Slot [16] registered [ 0.326120] acpiphp: Slot [17] registered [ 0.328104] acpiphp: Slot [18] registered [ 0.329120] acpiphp: Slot [19] registered [ 0.331127] acpiphp: Slot [20] registered [ 0.332105] acpiphp: Slot [21] registered [ 0.334082] acpiphp: Slot [22] registered [ 0.335078] acpiphp: Slot [23] registered [ 0.336070] acpiphp: Slot [24] registered [ 0.337074] acpiphp: Slot [25] registered [ 0.339126] acpiphp: Slot [26] registered [ 0.340122] acpiphp: Slot [27] registered [ 0.342109] acpiphp: Slot [28] registered [ 0.344103] acpiphp: Slot [29] registered [ 0.345129] acpiphp: Slot [30] registered [ 0.347114] acpiphp: Slot [31] registered [ 0.348062] PCI host bridge to bus 0000:00 [ 0.350031] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.352045] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.355028] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.357030] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.359021] pci_bus 0000:00: root bus resource [mem 0x380000000000-0x38007fffffff window] [ 0.362028] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.363154] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.366156] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.370198] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.376662] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.381059] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.383027] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.385018] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.386025] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.388628] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.391912] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.395060] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.398906] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.403016] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.413013] pci 0000:00:02.0: reg 0x20: [mem 0x380000000000-0x380000003fff 64bit pref] [ 0.418016] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.423675] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.429018] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.435017] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.445013] pci 0000:00:05.0: reg 0x20: [mem 0x380000004000-0x380000007fff 64bit pref] [ 0.453447] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.461017] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.466017] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.478017] pci 0000:00:06.0: reg 0x20: [mem 0x380000008000-0x38000000bfff 64bit pref] [ 0.489057] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.491451] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.494375] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.496421] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.498247] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.504033] iommu: Default domain type: Passthrough [ 0.506531] SCSI subsystem initialized [ 0.508136] ACPI: bus type USB registered [ 0.509124] usbcore: registered new interface driver usbfs [ 0.511079] usbcore: registered new interface driver hub [ 0.514099] usbcore: registered new device driver usb [ 0.515172] pps_core: LinuxPPS API ver. 1 registered [ 0.517011] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.520048] PTP clock support registered [ 0.522140] EDAC MC: Ver: 3.0.0 [ 0.524153] PCI: Using ACPI for IRQ routing [ 0.527050] NetLabel: Initializing [ 0.528015] NetLabel: domain hash size = 128 [ 0.530017] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.532122] NetLabel: unlabeled traffic allowed by default [ 0.535183] vgaarb: loaded [ 0.536346] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.539019] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.546606] clocksource: Switched to clocksource kvm-clock [ 0.657343] VFS: Disk quotas dquot_6.6.0 [ 0.659144] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.663894] *** VALIDATE ramfs *** [ 0.665218] *** VALIDATE hugetlbfs *** [ 0.666761] pnp: PnP ACPI init [ 0.668968] pnp: PnP ACPI: found 6 devices [ 0.686148] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.688851] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.690550] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.692179] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.693806] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.695882] pci_bus 0000:00: resource 8 [mem 0x380000000000-0x38007fffffff window] [ 0.698513] NET: Registered protocol family 2 [ 0.700742] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.705290] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.708780] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.714584] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.718181] TCP: Hash tables configured (established 65536 bind 65536) [ 0.720981] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.724160] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.726763] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.729353] NET: Registered protocol family 1 [ 0.731839] RPC: Registered named UNIX socket transport module. [ 0.733572] RPC: Registered udp transport module. [ 0.734844] RPC: Registered tcp transport module. [ 0.736170] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.738092] NET: Registered protocol family 44 [ 0.739344] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.740924] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.742612] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.744376] PCI: CLS 0 bytes, default 64 [ 0.745715] Unpacking initramfs... [ 2.165103] debug: unmapping init [mem 0xffffa0b67cc64000-0xffffa0b67ffcffff] [ 2.169214] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.171580] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.174822] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.693185] Initialise system trusted keyrings [ 2.694950] Key type blacklist registered [ 2.696771] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.709176] zbud: loaded [ 2.714114] *** VALIDATE nfs *** [ 2.715636] *** VALIDATE nfs4 *** [ 2.717546] pstore: using deflate compression [ 2.721083] Platform Keyring initialized [ 2.835429] NET: Registered protocol family 38 [ 2.837317] Key type asymmetric registered [ 2.839266] Asymmetric key parser 'x509' registered [ 2.841511] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.844707] io scheduler mq-deadline registered [ 2.846315] io scheduler kyber registered [ 2.847784] io scheduler bfq registered [ 2.849514] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.852468] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.855793] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.859141] ACPI: Power Button [PWRF] [ 2.953529] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.045751] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.140811] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.170326] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.203914] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.209517] Non-volatile memory driver v1.3 [ 3.211339] Linux agpgart interface v0.103 [ 3.245712] virtio_blk virtio1: [vda] 133840 512-byte logical blocks (68.5 MB/65.4 MiB) [ 3.248471] vda: detected capacity change from 0 to 68526080 [ 3.264529] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.267498] vdb: detected capacity change from 0 to 1073741824 [ 3.281562] libphy: Fixed MDIO Bus: probed [ 3.290849] usbcore: registered new interface driver usbserial_generic [ 3.295789] usbserial: USB Serial support registered for generic [ 3.298525] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.303098] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.305123] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.307817] mousedev: PS/2 mouse device common for all mice [ 3.311256] rtc_cmos 00:05: RTC can wake from S4 [ 3.313983] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.316313] rtc_cmos 00:05: registered as rtc0 [ 3.318884] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.321757] intel_pstate: CPU model not supported [ 3.324973] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.328126] hid: raw HID events driver (C) Jiri Kosina [ 3.331182] usbcore: registered new interface driver usbhid [ 3.331293] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.333212] usbhid: USB HID core driver [ 3.338061] drop_monitor: Initializing network drop monitor service [ 3.340551] Initializing XFRM netlink socket [ 3.342457] NET: Registered protocol family 10 [ 3.345046] Segment Routing with IPv6 [ 3.346834] NET: Registered protocol family 17 [ 3.349222] mpls_gso: MPLS GSO support [ 3.354622] RAS: Correctable Errors collector initialized. [ 3.356891] AVX version of gcm_enc/dec engaged. [ 3.358700] AES CTR mode by8 optimization enabled [ 3.439428] sched_clock: Marking stable (3439403168, 0)->(4348075901, -908672733) [ 3.442889] registered taskstats version 1 [ 3.444904] Loading compiled-in X.509 certificates [ 3.446815] zswap: loaded using pool lzo/zbud [ 3.469994] Key type big_key registered [ 3.480448] Key type encrypted registered [ 3.481794] ima: No TPM chip found, activating TPM-bypass! [ 3.483553] ima: Allocated hash algorithm: sha1 [ 3.485430] ima: No architecture policies found [ 3.487215] evm: Initialising EVM extended attributes: [ 3.489098] evm: security.selinux [ 3.490032] evm: security.ima [ 3.491148] evm: security.capability [ 3.492188] evm: HMAC attrs: 0x1 [ 3.494234] rtc_cmos 00:05: setting system clock to 2025-11-17 03:18:20 UTC (1763349500) [ 3.500063] debug: unmapping init [mem 0xffffffffa4603000-0xffffffffa47fffff] [ 3.502590] debug: unmapping init [mem 0xffffffffa3382000-0xffffffffa3658fff] [ 3.515137] Write protecting the kernel read-only data: 28672k [ 3.518466] debug: unmapping init [mem 0xffffffffa1a03000-0xffffffffa1bfffff] [ 3.520837] debug: unmapping init [mem 0xffffffffa2314000-0xffffffffa23fffff] [ 3.548925] 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.556636] systemd[1]: Detected virtualization kvm. [ 3.558700] systemd[1]: Detected architecture x86-64. [ 3.560243] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.588737] systemd[1]: No hostname configured. [ 3.590480] systemd[1]: Set hostname to . [ 3.592817] random: systemd: uninitialized urandom read (16 bytes read) [ 3.595112] systemd[1]: Initializing machine ID from random generator. [ 3.719446] random: systemd: uninitialized urandom read (16 bytes read) [ 3.722169] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 3.726738] random: systemd: uninitialized urandom read (16 bytes read) [ 3.729613] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 3.735456] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ OK ] Listening on Journal Socket. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Memstrack Anylazing Service. Starting Journal Service... [ OK ] Reached target Slices. [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Reached target Swap. [ OK ] Listening on udev Kernel Socket. Starting Apply Kernel Variables... Starting Setup Virtual Console... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. [ OK ] Started Setup Virtual Console. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.326481] device-mapper: uevent: version 1.0.3 [ 4.328645] 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. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 5.092573] virtio_net virtio0 ens2: renamed from eth0 [ 5.119539] scsi host0: ata_piix [ 5.134031] random: fast init done [ 5.215661] scsi host1: ata_piix [ 5.217940] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.221564] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.775138] dracut-initqueue[583]: RTNETLINK answers: File exists [ 9.997085] random: crng init done [ 9.997910] 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.352965] 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. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Timers. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Swap. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local File Systems. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.486590] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.722522] SELinux: Disabled at runtime. [ 11.780073] 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.788338] systemd[1]: Detected virtualization kvm. [ 11.790437] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.240284] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.243761] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.248564] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.252310] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.255342] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.265096] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.273584] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [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-serial\x2dgetty.slice. Mounting Huge Pages File System... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on Process Core Dump Socket. Starting Create list of required st…ce nodes for the current kernel... Mounting Kernel Debug File System... [ OK ] Listening on udev Control Socket. Starting Remount Root and Kernel File Systems... Mounting POSIX Message Queue File System... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. Starting Apply Kernel Variables... [ OK ] Listening on udev Kernel Socket. [ OK ] Created slice User and Session Slice. [ OK ] Reached target rpc_pipefs.target. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [ OK ] Created slice system-getty.slice. Activating swap /dev/disk/by-label/SWAP... [ OK ] Stopped target Initrd File Systems. [ OK ] Reached target Paths. Starting udev Coldplug all Devices... [ OK ] Reached target Slices. [ 12.447057] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Started Journal Service. [ OK ] Mounted Huge Pages File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Kernel Debug File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Apply Kernel Variables. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... 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 ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 12.721480] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 13.012606] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.014075] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.151140] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.164558] EDAC sbridge: Ver: 1.1.2 [ 14.266727] Key type dns_resolver registered [ 14.573352] NFS: Registering the id_resolver key type [ 14.575144] Key type id_resolver registered [ 14.576509] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ 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 update of the root trust anchor for DNSSEC. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Restore /run/initramfs on shutdown... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started irqbalance daemon. Starting Login Service... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started dnf makecache --timer. [ OK ] Reached target sshd-keygen.target. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... [ OK ] Started OpenSSH server daemon. Starting Hostname Service... [ 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 Command Scheduler. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ 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 Notify NFS peers of a restart... Starting System Logging Service... [ 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 oleg116-client login: [ 57.771201] libcfs: loading out-of-tree module taints kernel. [ 57.818123] Key type ._llcrypt registered [ 57.819702] Key type .llcrypt registered [ 58.869738] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 58.884030] alg: No test for adler32 (adler32-zlib) [ 60.493509] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 61.470655] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 63.320157] Key type lgssc registered [ 64.904562] Lustre: Echo OBD driver; http://www.lustre.org/ [ 198.211150] Lustre: Mounted lustre-client [ 202.018421] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 217.218624] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing check_logdir /tmp/testlogs/ [ 220.359431] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing yml_node [ 223.373261] Lustre: DEBUG MARKER: Client: 2.16.61.42 [ 223.712490] Lustre: lustre-OST0000-osc-ffffa0b6d2309800: disconnect after 23s idle [ 225.336732] Lustre: DEBUG MARKER: MDS: 2.16.61.42 [ 227.810814] Lustre: DEBUG MARKER: OSS: 2.16.61.42 [ 229.293822] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Sun Nov 16 22:22:05 EST 2025 [ 242.920692] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 244.116785] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 246.037128] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 256.297246] LustreError: 8990:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 256.328965] Lustre: Unmounted lustre-client [ 342.703128] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 345.784398] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 370.515873] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 399.163046] hrtimer: interrupt took 5500850 ns [ 402.512613] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 22:24:58 (1763349898) [ 405.126047] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 406.638286] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 22:25:02 (1763349902) [ 431.869694] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 434.839087] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 450.546372] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 461.377069] Lustre: Mounted lustre-client [ 464.521861] LustreError: 14434:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6d16eb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 464.557495] LustreError: 14434:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 464.564852] LustreError: 14434:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 464.643877] Lustre: Unmounted lustre-client [ 488.845961] Key type lgssc unregistered [ 489.070346] LNet: 14841:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 489.076149] LNetError: 14841:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 490.094871] LNet: Removed LNI 192.168.201.16@tcp [ 490.700231] Key type .llcrypt unregistered [ 490.701696] Key type ._llcrypt unregistered [ 502.648806] Key type ._llcrypt registered [ 502.650489] Key type .llcrypt registered [ 503.767696] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 22:26:39 (1763349999) [ 504.966595] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 505.096288] alg: No test for adler32 (adler32-zlib) [ 506.121639] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 506.329983] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 507.968227] Key type lgssc registered [ 509.011763] Lustre: Echo OBD driver; http://www.lustre.org/ [ 573.444546] Lustre: Mounted lustre-client [ 660.724930] LustreError: 18150:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6d825d800: lov tgt 1990 not cleaned! deathrow=0, lovrc=1 [ 660.737853] LustreError: 18150:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 660.788789] Lustre: Unmounted lustre-client [ 694.685907] Key type lgssc unregistered [ 694.951578] LNet: 18615:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 694.961332] LNetError: 18615:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 696.039578] LNet: Removed LNI 192.168.201.16@tcp [ 696.693093] Key type .llcrypt unregistered [ 696.696031] Key type ._llcrypt unregistered [ 705.711358] Key type ._llcrypt registered [ 705.712872] Key type .llcrypt registered [ 706.865805] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 22:30:02 (1763350202) [ 708.942359] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 708.973502] alg: No test for adler32 (adler32-zlib) [ 710.084858] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 710.270531] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 711.924118] Key type lgssc registered [ 713.145183] Lustre: Echo OBD driver; http://www.lustre.org/ [ 752.358390] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 755.435993] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 772.853659] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 778.786083] Lustre: Mounted lustre-client [ 787.032944] LustreError: 21724:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6c5324800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 787.063780] LustreError: 21724:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 787.168920] Lustre: Unmounted lustre-client [ 814.630284] Key type lgssc unregistered [ 814.903926] LNet: 22128:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 814.915295] LNetError: 22128:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 815.989185] LNet: Removed LNI 192.168.201.16@tcp [ 816.657964] Key type .llcrypt unregistered [ 816.662431] Key type ._llcrypt unregistered [ 824.976358] Key type ._llcrypt registered [ 824.979878] Key type .llcrypt registered [ 825.984520] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 22:32:01 (1763350321) [ 827.422177] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 827.456641] alg: No test for adler32 (adler32-zlib) [ 828.452934] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 828.688776] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 830.344211] Key type lgssc registered [ 831.363601] Lustre: Echo OBD driver; http://www.lustre.org/ [ 867.774316] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 870.841443] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 886.913482] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 899.152773] Lustre: Mounted lustre-client [ 903.924709] LustreError: 24828:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6c5325800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 903.956737] LustreError: 24828:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 904.021370] Lustre: Unmounted lustre-client [ 929.947753] Key type lgssc unregistered [ 930.314062] LNet: 25230:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 930.342091] LNetError: 25230:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 931.374660] LNet: Removed LNI 192.168.201.16@tcp [ 931.926552] Key type .llcrypt unregistered [ 931.928686] Key type ._llcrypt unregistered [ 947.043059] Key type ._llcrypt registered [ 947.045115] Key type .llcrypt registered [ 948.679658] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 22:34:03 (1763350443) [ 950.897480] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 950.922718] alg: No test for adler32 (adler32-zlib) [ 952.019332] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 952.269106] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 953.969118] Key type lgssc registered [ 955.077245] Lustre: Echo OBD driver; http://www.lustre.org/ [ 995.766899] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 998.370551] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1013.806897] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1026.077412] Lustre: Mounted lustre-client [ 1031.154528] Lustre: lustre-MDT0000-mdc-ffffa0b6c4712800: Connection to lustre-MDT0000 (at 192.168.201.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1041.397425] Lustre: lustre-MDT0001-mdc-ffffa0b6c4712800: Connection to lustre-MDT0001 (at 192.168.201.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1052.640253] Lustre: 26186:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763350533/real 1763350533] req@00000000ce867e3c x1849006960416768/t0(0) o400->MGC192.168.201.116@tcp@192.168.201.116@tcp:26/25 lens 224/224 e 0 to 1 dl 1763350549 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1052.655547] LustreError: MGC192.168.201.116@tcp: Connection to MGS (at 192.168.201.116@tcp) was lost; in progress operations using this service will fail [ 1055.224516] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1065.519722] LustreError: 28408:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1065.572776] Lustre: Unmounted lustre-client [ 1076.869583] Key type lgssc unregistered [ 1077.091864] LNet: 28798:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1077.104905] LNetError: 28798:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1077.117539] LNet: Removed LNI 192.168.201.16@tcp [ 1077.696471] Key type .llcrypt unregistered [ 1077.704916] Key type ._llcrypt unregistered [ 1089.320620] Key type ._llcrypt registered [ 1089.328295] Key type .llcrypt registered [ 1090.608628] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 22:36:26 (1763350586) [ 1092.765191] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1092.776421] alg: No test for adler32 (adler32-zlib) [ 1093.820240] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 1094.024889] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 1095.704304] Key type lgssc registered [ 1096.855954] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1142.084897] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1146.308870] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1166.094400] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1168.687232] Lustre: Mounted lustre-client [ 1174.003925] Lustre: lustre-OST0000-osc-ffffa0b6c5322800: Connection to lustre-OST0000 (at 192.168.201.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1180.162481] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1190.456605] LustreError: 31921:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1190.501399] Lustre: Unmounted lustre-client [ 1214.504195] Key type lgssc unregistered [ 1214.717642] LNet: 32324:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1214.723984] LNetError: 32324:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1214.741482] LNet: Removed LNI 192.168.201.16@tcp [ 1215.321932] Key type .llcrypt unregistered [ 1215.323297] Key type ._llcrypt unregistered [ 1227.698334] Key type ._llcrypt registered [ 1227.699792] Key type .llcrypt registered [ 1228.782439] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 22:38:44 (1763350724) [ 1231.525419] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1231.622603] alg: No test for adler32 (adler32-zlib) [ 1232.848477] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 1233.162884] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 1234.786689] Key type lgssc registered [ 1236.283510] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1280.580312] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1284.230506] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1300.340298] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1306.671359] Lustre: Mounted lustre-client [ 1310.554521] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1314.415696] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1326.071360] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2025-11-1622h40m21s [ 1328.288582] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1332.200883] Lustre: lustre-MDT0000-mdc-ffffa0b6c5326800: Connection to lustre-MDT0000 (at 192.168.201.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1339.287280] LustreError: 36028:0:(lmv_obd.c:1435:lmv_statfs()) lustre-MDT0000-mdc-ffffa0b6c5326800: can't stat MDS #0: rc = -19 [ 1341.087568] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1349.607216] LustreError: MGC192.168.201.116@tcp: Connection to MGS (at 192.168.201.116@tcp) was lost; in progress operations using this service will fail [ 1349.625500] Lustre: Evicted from MGS (at 192.168.201.116@tcp) after server handle changed from 0xe4f24ab4353357aa to 0xe4f24ab435335b23 [ 1349.630981] Lustre: MGC192.168.201.116@tcp: Connection restored to 192.168.201.116@tcp (at 192.168.201.116@tcp) [ 1350.636096] LustreError: lustre-MDT0000-mdc-ffffa0b6c5326800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1350.649691] Lustre: lustre-MDT0000-mdc-ffffa0b6c5326800: Connection restored to 192.168.201.116@tcp (at 192.168.201.116@tcp) [ 1356.459553] Lustre: DEBUG MARKER: Wait for df (36028) ... [ 1358.257424] Lustre: DEBUG MARKER: done [ 1359.685858] LustreError: 36643:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6c5326800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1359.699750] LustreError: 36643:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1359.746476] Lustre: Unmounted lustre-client [ 1398.198233] Key type lgssc unregistered [ 1398.457077] LNet: 37047:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1398.468180] LNetError: 37047:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1398.517316] LNet: Removed LNI 192.168.201.16@tcp [ 1399.209212] Key type .llcrypt unregistered [ 1399.210852] Key type ._llcrypt unregistered [ 1441.411261] Key type ._llcrypt registered [ 1441.412378] Key type .llcrypt registered [ 1441.849680] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1441.860798] alg: No test for adler32 (adler32-zlib) [ 1442.872401] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 1443.126171] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 1444.800239] Key type lgssc registered [ 1445.969263] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1523.795531] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1528.494227] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1549.714923] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1594.301741] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 22:44:50 (1763351090) [ 1621.941301] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1625.498508] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1641.260136] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1647.163896] Lustre: Mounted lustre-client [ 1651.591607] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2025-11-1622h45m46s [ 1653.169228] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1660.028096] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1662.691739] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1682.913735] Lustre: 43922:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763351159/real 1763351159] req@000000007c366c0c x1849007475268352/t0(0) o101->lustre-MDT0000-mdc-ffffa0b6c42fb800@192.168.201.116@tcp:12/10 lens 576/1152 e 0 to 1 dl 1763351179 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:0 [ 1682.961217] Lustre: lustre-MDT0000-mdc-ffffa0b6c42fb800: Connection to lustre-MDT0000 (at 192.168.201.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1682.995594] Lustre: lustre-MDT0000-mdc-ffffa0b6c42fb800: Connection restored to 192.168.201.116@tcp (at 192.168.201.116@tcp) [ 1704.316580] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1707.154535] Lustre: DEBUG MARKER: done [ 1710.201799] LustreError: 44405:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6c42fb800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1710.226430] LustreError: 44405:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1710.281533] Lustre: Unmounted lustre-client [ 1735.257100] Key type lgssc unregistered [ 1735.579948] LNet: 44808:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1735.593829] LNetError: 44808:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1735.654327] LNet: Removed LNI 192.168.201.16@tcp [ 1736.314439] Key type .llcrypt unregistered [ 1736.316382] Key type ._llcrypt unregistered [ 1778.828243] Key type ._llcrypt registered [ 1778.829571] Key type .llcrypt registered [ 1779.051703] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1779.067126] alg: No test for adler32 (adler32-zlib) [ 1780.252308] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 1780.482981] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 1782.176236] Key type lgssc registered [ 1783.456408] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1856.300780] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1859.830203] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1878.233576] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1916.057525] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 22:50:11 (1763351411) [ 1917.829708] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1919.469590] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 22:50:15 (1763351415) [ 1942.158964] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 22:50:37 (1763351437) [ 1968.462825] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1972.381560] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1989.036099] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1994.784971] Lustre: Mounted lustre-client [ 1997.916351] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 2000.569990] LustreError: 52647:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6d16ea800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2000.606546] LustreError: 52647:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2000.677775] Lustre: Unmounted lustre-client [ 2011.084722] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 2053.357913] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2056.909582] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2057.517061] Lustre: Mounted lustre-client [ 2060.328692] LustreError: 53907:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6d1350000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2060.353372] LustreError: 53907:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2060.356605] LustreError: 53907:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 2060.415257] Lustre: Unmounted lustre-client [ 2117.396985] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2120.960332] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2121.570155] Lustre: Mounted lustre-client [ 2124.398461] LustreError: 55029:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6d16ee800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2124.420284] LustreError: 55029:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2124.423286] LustreError: 55029:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 2124.468590] Lustre: Unmounted lustre-client [ 2140.173279] Lustre: DEBUG MARKER: files compared the same [ 2161.987592] Key type lgssc unregistered [ 2162.223407] LNet: 55734:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2162.227638] LNetError: 55734:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2162.246168] LNet: Removed LNI 192.168.201.16@tcp [ 2162.758179] Key type .llcrypt unregistered [ 2162.763717] Key type ._llcrypt unregistered [ 2174.922839] Key type ._llcrypt registered [ 2174.924426] Key type .llcrypt registered [ 2176.131226] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 22:54:31 (1763351671) [ 2178.017848] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2178.043554] alg: No test for adler32 (adler32-zlib) [ 2179.054754] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 2179.256818] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 2180.898316] Key type lgssc registered [ 2182.022913] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2219.204108] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2222.263996] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2237.737278] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2249.799161] Lustre: Mounted lustre-client [ 2252.580557] LustreError: 58827:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6c5a7a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2252.597385] LustreError: 58827:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2252.651250] Lustre: Unmounted lustre-client [ 2277.101337] Key type lgssc unregistered [ 2277.325302] LNet: 59229:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2277.336319] LNetError: 59229:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2278.390450] LNet: Removed LNI 192.168.201.16@tcp [ 2279.136627] Key type .llcrypt unregistered [ 2279.138810] Key type ._llcrypt unregistered [ 2290.649714] Key type ._llcrypt registered [ 2290.651278] Key type .llcrypt registered [ 2291.819632] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 22:56:27 (1763351787) [ 2293.620842] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2293.654335] alg: No test for adler32 (adler32-zlib) [ 2294.687134] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 2294.913454] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 2296.560201] Key type lgssc registered [ 2297.481480] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2369.531570] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2390.439286] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2393.157678] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2415.407463] Key type lgssc unregistered [ 2415.568451] LNet: 62632:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2415.580745] LNetError: 62632:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2415.591449] LNet: Removed LNI 192.168.201.16@tcp [ 2416.002817] Key type .llcrypt unregistered [ 2416.004994] Key type ._llcrypt unregistered [ 2425.179622] Key type ._llcrypt registered [ 2425.181320] Key type .llcrypt registered [ 2426.005458] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 22:58:42 (1763351922) [ 2428.090657] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2428.097342] alg: No test for adler32 (adler32-zlib) [ 2429.023466] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 2429.164237] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 2430.784176] Key type lgssc registered [ 2431.501532] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2474.548784] Lustre: Mounted lustre-client [ 2480.487510] LustreError: 65119:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6d16d8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2480.501385] LustreError: 65119:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2480.535286] Lustre: Unmounted lustre-client [ 2507.328915] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 23:00:03 (1763352003) [ 2577.225913] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2596.380705] Lustre: Mounted lustre-client [ 2602.427715] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2602.431874] LustreError: 69091:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffffa0b6d16e9000: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 2602.444196] Lustre: 63614:0:(llite_lib.c:4226:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.201.116@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 2602.453188] LustreError: 69091:0:(ldlm_resource.c:981:ldlm_resource_complain()) lustre-OST0000-osc-ffffa0b6d16e9000: namespace resource [0x240000401:0x2:0x0].0x0 (00000000bff1f3ba) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 2612.723094] LustreError: 69091:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2612.726324] LustreError: 69091:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 2612.769186] Lustre: Unmounted lustre-client [ 2646.983689] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 23:02:22 (1763352142) [ 2654.549996] Key type lgssc unregistered [ 2654.737528] LNet: 70301:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2654.749396] LNetError: 70301:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2654.768220] LNet: Removed LNI 192.168.201.16@tcp [ 2655.197622] Key type .llcrypt unregistered [ 2655.205935] Key type ._llcrypt unregistered [ 2662.986967] Key type ._llcrypt registered [ 2662.988944] Key type .llcrypt registered [ 2663.242564] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2663.255765] alg: No test for adler32 (adler32-zlib) [ 2664.201214] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 2664.348699] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 2665.977261] Key type lgssc registered [ 2666.732036] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2693.872555] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2695.863816] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2706.655959] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2713.588764] Lustre: Mounted lustre-client [ 2719.378732] LustreError: 72820:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6d8978000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2719.391632] LustreError: 72820:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2719.431378] Lustre: Unmounted lustre-client [ 2749.217759] Key type lgssc unregistered [ 2749.411766] LNet: 73223:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2749.418129] LNetError: 73223:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2749.436069] LNet: Removed LNI 192.168.201.16@tcp [ 2749.823531] Key type .llcrypt unregistered [ 2749.826081] Key type ._llcrypt unregistered [ 2755.308736] Key type ._llcrypt registered [ 2755.310499] Key type .llcrypt registered [ 2755.541328] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2755.550985] alg: No test for adler32 (adler32-zlib) [ 2756.477834] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 2756.607934] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 2758.232319] Key type lgssc registered [ 2758.954557] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2825.889723] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2827.994655] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2838.774303] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2840.238442] Lustre: Mounted lustre-client [ 2851.504410] LustreError: 76161:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6d1350800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2851.535742] LustreError: 76161:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2851.576401] Lustre: Unmounted lustre-client [ 2872.749563] Key type lgssc unregistered [ 2872.927137] LNet: 76563:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2872.930695] LNetError: 76563:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2872.946940] LNet: Removed LNI 192.168.201.16@tcp [ 2873.281180] Key type .llcrypt unregistered [ 2873.283082] Key type ._llcrypt unregistered [ 2881.281832] Key type ._llcrypt registered [ 2881.283147] Key type .llcrypt registered [ 2882.005588] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 23:06:18 (1763352378) [ 2883.333700] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2883.343293] alg: No test for adler32 (adler32-zlib) [ 2884.293755] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 2884.428606] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 2886.065270] Key type lgssc registered [ 2886.683428] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2913.004543] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2915.345246] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2926.466253] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2927.889507] Lustre: Mounted lustre-client [ 2934.675970] LustreError: 79675:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6c4302800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2934.690740] LustreError: 79675:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2934.729863] Lustre: Unmounted lustre-client [ 2935.050040] Lustre: Mounted lustre-client [ 2936.434488] LustreError: 79755:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6d1350800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2936.448856] LustreError: 79755:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2936.451257] LustreError: 79755:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 2936.505602] Lustre: Unmounted lustre-client [ 2966.079520] Key type lgssc unregistered [ 2966.286376] LNet: 80157:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2966.291382] LNetError: 80157:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2966.309178] LNet: Removed LNI 192.168.201.16@tcp [ 2966.739679] Key type .llcrypt unregistered [ 2966.741927] Key type ._llcrypt unregistered [ 2972.941747] Key type ._llcrypt registered [ 2972.943222] Key type .llcrypt registered [ 2973.135720] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2973.152381] alg: No test for adler32 (adler32-zlib) [ 2974.054195] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 2974.179656] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 2975.792156] Key type lgssc registered [ 2976.469851] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3003.295614] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3005.618195] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3015.627941] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3016.956933] Lustre: Mounted lustre-client [ 3028.672295] LustreError: 82696:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6d811c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3028.687889] LustreError: 82696:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3028.738827] Lustre: Unmounted lustre-client [ 3050.816860] Key type lgssc unregistered [ 3050.995144] LNet: 83099:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3051.006579] LNetError: 83099:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3051.025914] LNet: Removed LNI 192.168.201.16@tcp [ 3051.485289] Key type .llcrypt unregistered [ 3051.486986] Key type ._llcrypt unregistered [ 3060.813448] Key type ._llcrypt registered [ 3060.815011] Key type .llcrypt registered [ 3061.646491] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 23:09:17 (1763352557) [ 3062.906563] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3062.959962] alg: No test for adler32 (adler32-zlib) [ 3063.867688] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 3063.983635] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 3065.608495] Key type lgssc registered [ 3066.234747] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3092.049403] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3093.966861] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3103.293128] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3109.882676] Lustre: Mounted lustre-client [ 3122.697996] Lustre: lustre: nosquash_nids is cleared [ 3122.709832] Lustre: lustre: root_squash is set to 500:500 [ 3130.119391] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.201.16@tcp 192.168.0.[2,10]@tcp [ 3138.564632] Lustre: lustre: nosquash_nids set to 192.168.201.16/24@tcp [ 3146.270318] LustreError: 86584:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6c6f33000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3146.288844] LustreError: 86584:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3146.336415] Lustre: Unmounted lustre-client [ 3175.794609] Key type lgssc unregistered [ 3175.955462] LNet: 86986:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3175.960831] LNetError: 86986:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3175.972755] LNet: Removed LNI 192.168.201.16@tcp [ 3176.452203] Key type .llcrypt unregistered [ 3176.454794] Key type ._llcrypt unregistered [ 3186.120345] Key type ._llcrypt registered [ 3186.122191] Key type .llcrypt registered [ 3186.948510] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 23:11:23 (1763352683) [ 3188.459062] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3189.445288] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 23:11:25 (1763352685) [ 3190.898817] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3190.917959] alg: No test for adler32 (adler32-zlib) [ 3191.895436] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 3192.048176] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 3193.689108] Key type lgssc registered [ 3194.531845] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3223.929595] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3226.004736] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3235.638831] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3235.884171] Lustre: lustre: root_squash is set to 500:500 [ 3235.905210] Lustre: lustre: nosquash_nids set to 192.168.201.16/24@tcp [ 3236.959269] Lustre: Mounted lustre-client [ 3248.843410] LustreError: 90450:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6d220c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3248.856860] LustreError: 90450:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3248.894856] Lustre: Unmounted lustre-client [ 3269.548650] Key type lgssc unregistered [ 3269.713046] LNet: 90852:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3269.716899] LNetError: 90852:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3269.728827] LNet: Removed LNI 192.168.201.16@tcp [ 3270.187306] Key type .llcrypt unregistered [ 3270.190617] Key type ._llcrypt unregistered [ 3278.256489] Key type ._llcrypt registered [ 3278.257819] Key type .llcrypt registered [ 3279.185413] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 3280.250824] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 23:12:56 (1763352776) [ 3290.136826] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3290.143568] alg: No test for adler32 (adler32-zlib) [ 3291.069930] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 3291.211354] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 3292.816179] Key type lgssc registered [ 3293.503589] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3335.643542] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3337.973243] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3350.585662] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3403.972974] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3405.847738] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3417.002212] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3426.927493] Lustre: Mounted lustre-client [ 3442.396144] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffffa0b6c4717800.ost_server_uuid 50 [ 3443.091978] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffffa0b6c4717800.ost_server_uuid in FULL state after 0 sec [ 3443.379955] Lustre: Mounted lustre-client [ 3444.709223] LustreError: 97744:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6d16de000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3444.718829] LustreError: 97744:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 3444.753949] Lustre: Unmounted lustre-client [ 3445.225984] LustreError: 97763:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3445.228582] LustreError: 97763:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 3445.271506] Lustre: Unmounted lustre-client [ 3477.592145] Key type lgssc unregistered [ 3477.742128] LNet: 98315:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3477.746892] LNetError: 98315:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3477.760686] LNet: Removed LNI 192.168.201.16@tcp [ 3478.065447] Key type .llcrypt unregistered [ 3478.067473] Key type ._llcrypt unregistered [ 3497.676223] Key type ._llcrypt registered [ 3497.720820] Key type .llcrypt registered [ 3498.052609] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3498.059401] alg: No test for adler32 (adler32-zlib) [ 3498.994079] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 3499.134216] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 3500.768618] Key type lgssc registered [ 3501.448502] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3539.469700] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3541.047615] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3550.175778] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3575.477323] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 23:17:51 (1763353071) [ 3619.601639] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3621.259259] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3630.914753] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3632.175335] Lustre: Mounted lustre-client [ 3652.586540] Lustre: lustre-MDT0000-mdc-ffffa0b6c4716800: Connection to lustre-MDT0000 (at 192.168.201.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3653.600257] Lustre: 99099:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763353134/real 1763353134] req@00000000a8f81781 x1849009631138944/t0(0) o400->lustre-OST0000-osc-ffffa0b6c4716800@192.168.201.116@tcp:28/4 lens 224/224 e 0 to 1 dl 1763353150 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3658.720363] Lustre: 99098:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763353139/real 1763353139] req@000000004eed7a90 x1849009631139456/t0(0) o400->lustre-OST0000-osc-ffffa0b6c4716800@192.168.201.116@tcp:28/4 lens 224/224 e 0 to 1 dl 1763353155 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3663.840128] Lustre: 99101:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763353144/real 1763353144] req@00000000d758dde8 x1849009631139968/t0(0) o400->lustre-OST0000-osc-ffffa0b6c4716800@192.168.201.116@tcp:28/4 lens 224/224 e 0 to 1 dl 1763353160 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3667.944205] LustreError: MGC192.168.201.116@tcp: Connection to MGS (at 192.168.201.116@tcp) was lost; in progress operations using this service will fail [ 3667.952116] Lustre: 106224:0:(mgc_request.c:1910:mgc_process_log()) MGC192.168.201.116@tcp: IR log lustre-cliir failed, not fatal: rc = -5 [ 3667.955117] Lustre: Evicted from MGS (at 192.168.201.116@tcp) after server handle changed from 0x95a1e2d596abe596 to 0x95a1e2d596abe82f [ 3667.967274] Lustre: MGC192.168.201.116@tcp: Connection restored to 192.168.201.116@tcp (at 192.168.201.116@tcp) [ 3671.255347] Lustre: lustre-MDT0000-mdc-ffffa0b6c4716800: Connection restored to 192.168.201.116@tcp (at 192.168.201.116@tcp) [ 3671.754260] LustreError: 106946:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6c4716800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3671.762374] LustreError: 106946:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3671.795987] Lustre: Unmounted lustre-client [ 3691.392654] Key type lgssc unregistered [ 3691.523879] LNet: 107347:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3691.528906] LNetError: 107347:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3691.539899] LNet: Removed LNI 192.168.201.16@tcp [ 3691.854673] Key type .llcrypt unregistered [ 3691.856032] Key type ._llcrypt unregistered [ 3698.160961] Key type ._llcrypt registered [ 3698.162252] Key type .llcrypt registered [ 3698.726574] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 23:19:55 (1763353195) [ 3710.743860] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3710.751648] alg: No test for adler32 (adler32-zlib) [ 3711.654403] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 3711.766304] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 3713.368398] Key type lgssc registered [ 3713.876200] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3732.964198] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3776.353336] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3777.951521] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3787.766594] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3789.015155] Lustre: Mounted lustre-client [ 3789.932337] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 3790.669417] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 23:21:27 (1763353287) [ 3885.186099] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3895.478894] LustreError: 118094:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3895.503266] Lustre: Unmounted lustre-client [ 3956.429146] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3958.003063] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3967.581116] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3992.999920] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 23:24:49 (1763353489) [ 4035.063885] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4036.580199] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4045.818425] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4051.445858] Lustre: Mounted lustre-client [ 4053.044805] LustreError: 125496:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6c76f8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4053.053636] LustreError: 125496:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4053.055755] LustreError: 125496:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4053.085759] Lustre: Unmounted lustre-client [ 4083.862238] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 23:26:20 (1763353580) [ 4123.822530] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4125.445195] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4134.931438] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4140.554893] Lustre: Mounted lustre-client [ 4142.596732] LustreError: 129537:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6c72f9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4142.606942] LustreError: 129537:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4142.610355] LustreError: 129537:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4142.640630] Lustre: Unmounted lustre-client [ 4153.740698] Key type lgssc unregistered [ 4153.869468] LNet: 129936:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4153.873522] LNetError: 129936:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4153.884534] LNet: Removed LNI 192.168.201.16@tcp [ 4154.164711] Key type .llcrypt unregistered [ 4154.166114] Key type ._llcrypt unregistered [ 4160.050154] Key type ._llcrypt registered [ 4160.051078] Key type .llcrypt registered [ 4160.577664] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 23:27:36 (1763353656) [ 4161.585886] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4161.597397] alg: No test for adler32 (adler32-zlib) [ 4162.486718] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 4162.587465] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 4164.192735] Key type lgssc registered [ 4164.654747] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4185.733158] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4187.171940] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4194.874740] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4205.580230] Lustre: Mounted lustre-client [ 4216.756098] LustreError: 133032:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6c5ca4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4216.765544] LustreError: 133032:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4216.796363] Lustre: Unmounted lustre-client [ 4235.867372] Key type lgssc unregistered [ 4236.011067] LNet: 133435:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4236.016284] LNetError: 133435:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4236.033836] LNet: Removed LNI 192.168.201.16@tcp [ 4236.335151] Key type .llcrypt unregistered [ 4236.336794] Key type ._llcrypt unregistered [ 4243.269658] Key type ._llcrypt registered [ 4243.271136] Key type .llcrypt registered [ 4243.820824] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 23:29:00 (1763353740) [ 4244.823307] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4244.854529] alg: No test for adler32 (adler32-zlib) [ 4245.768997] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 4245.896668] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 4247.504174] Key type lgssc registered [ 4248.103783] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4270.524943] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4272.288470] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4280.241346] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4285.951058] Lustre: Mounted lustre-client [ 4291.047320] Lustre: lustre-OST0000-osc-ffffa0b6c76fe000: Connection to lustre-OST0000 (at 192.168.201.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4296.990679] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffffa0b6c76fe000.ost_server_uuid 50 [ 4297.893603] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 4307.567084] LustreError: 136965:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6c76fe000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4307.571122] LustreError: 136965:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4307.593489] Lustre: Unmounted lustre-client [ 4320.121819] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 23:30:16 (1763353816) [ 4332.359972] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4333.857434] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4341.488795] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4350.732681] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4350.958718] Lustre: Mounted lustre-client [ 4356.073919] Lustre: lustre-OST0000-osc-ffffa0b6c7321000: Connection to lustre-OST0000 (at 192.168.201.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4371.564692] LustreError: 139616:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6c7321000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4371.575254] LustreError: 139616:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 4371.578207] LustreError: 139616:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4371.609220] Lustre: Unmounted lustre-client [ 4398.730981] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 23:31:35 (1763353895) [ 4410.843740] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4412.369286] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4420.319424] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4428.772228] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4429.008714] Lustre: Mounted lustre-client [ 4434.404515] Lustre: lustre-OST0000-osc-ffffa0b6d1357000: Connection to lustre-OST0000 (at 192.168.201.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4454.702972] LustreError: 142370:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6d1357000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4454.708526] LustreError: 142370:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 4454.715593] LustreError: 142370:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 4454.718495] LustreError: 142370:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 4454.742297] Lustre: Unmounted lustre-client [ 4480.090550] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 23:32:56 (1763353976) [ 4516.469418] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4518.035834] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4527.472354] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4560.201504] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4561.658532] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4568.779917] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4587.164990] Lustre: Mounted lustre-client [ 4616.254177] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4616.938496] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 4617.361549] LustreError: 148948:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6c70e8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4617.367593] LustreError: 148948:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 4617.375244] LustreError: 148948:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4617.377955] LustreError: 148948:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 4617.402738] Lustre: Unmounted lustre-client [ 4645.432954] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 23:35:41 (1763354141) [ 4658.112665] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4659.616763] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4667.017335] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4684.379771] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4699.573294] Lustre: Mounted lustre-client [ 4728.330887] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4729.019340] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 4730.343599] Lustre: lustre-OST0001-osc-ffffa0b6c76fa000: Connection to lustre-OST0001 (at 192.168.201.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4733.058922] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4743.338523] LustreError: 152527:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 4743.341826] LustreError: 152527:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4743.365282] Lustre: Unmounted lustre-client [ 4776.048484] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 23:37:52 (1763354272) [ 4788.398426] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4789.950366] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4797.768454] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4803.072271] Lustre: Mounted lustre-client [ 4812.410192] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4815.165384] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 2 sec [ 4820.520278] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffffa0b6c4717800.ost_server_uuid 50 [ 4821.309820] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffffa0b6c4717800.ost_server_uuid in FULL state after 0 sec [ 4822.452696] LustreError: 155962:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6c4717800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4822.469481] LustreError: 155962:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 4822.474545] LustreError: 155962:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 4822.516069] Lustre: Unmounted lustre-client [ 4822.710530] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 4822.713520] Lustre: Skipped 3 previous similar messages [ 4822.784407] Lustre: Mounted lustre-client [ 4823.303077] LustreError: 156026:0:(sec.c:410:import_sec_validate_get()) import 00000000b2986bb5 (NEW) with no sec [ 4859.916459] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 23:39:16 (1763354356) [ 4873.661665] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4875.177867] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4882.672915] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4890.497596] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4890.711320] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 4890.776738] Lustre: Mounted lustre-client [ 4902.134929] LustreError: 158883:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6d1d05800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4902.140236] LustreError: 158883:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 4902.150549] LustreError: 158883:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 4902.153452] LustreError: 158883:0:(obd_class.h:479:obd_check_dev()) Skipped 18 previous similar messages [ 4902.186035] Lustre: Unmounted lustre-client [ 4902.187552] Lustre: Skipped 1 previous similar message [ 4928.240891] Key type lgssc unregistered [ 4928.370813] LNet: 159330:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4928.374137] LNetError: 159330:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4928.381901] LNet: Removed LNI 192.168.201.16@tcp [ 4928.647443] Key type .llcrypt unregistered [ 4928.648611] Key type ._llcrypt unregistered [ 4934.614627] Key type ._llcrypt registered [ 4934.615989] Key type .llcrypt registered [ 4935.183893] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 23:40:31 (1763354431) [ 4935.665061] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4935.671762] alg: No test for adler32 (adler32-zlib) [ 4936.566024] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 4936.669831] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 4938.264184] Key type lgssc registered [ 4938.737524] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4960.220819] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4961.824584] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4969.323768] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4977.237671] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4977.400658] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 4978.445208] Lustre: Mounted lustre-client [ 4986.356781] LustreError: lustre-MDT0001-mdc-ffffa0b6d1352000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 4986.365505] Lustre: lustre-MDT0001-mdc-ffffa0b6d1352000: Connection restored to 192.168.201.116@tcp (at 192.168.201.116@tcp) [ 4988.698484] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4989.369324] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 5002.735843] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 5004.163496] LustreError: 163576:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6d1352000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5004.169392] LustreError: 163576:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 5004.203230] Lustre: Unmounted lustre-client [ 5024.629639] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 23:42:01 (1763354521) [ 5061.376888] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5062.972176] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5072.213500] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5072.493762] Lustre: Mounted lustre-client [ 5085.406833] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5086.169152] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 5097.453312] Lustre: lustre-OST0001-osc-ffffa0b6c7321000: Connection to lustre-OST0001 (at 192.168.201.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5101.861758] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5112.103995] LustreError: 168298:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 5112.109494] LustreError: 168298:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 5112.134570] Lustre: Unmounted lustre-client [ 5145.661206] Key type lgssc unregistered [ 5145.802141] LNet: 168703:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5145.806175] LNetError: 168703:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5145.825723] LNet: Removed LNI 192.168.201.16@tcp [ 5146.145831] Key type .llcrypt unregistered [ 5146.147430] Key type ._llcrypt unregistered [ 5164.992492] Key type ._llcrypt registered [ 5164.996293] Key type .llcrypt registered [ 5165.261617] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5165.267944] alg: No test for adler32 (adler32-zlib) [ 5166.167222] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 5166.272130] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 5167.880163] Key type lgssc registered [ 5168.386700] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5204.384482] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5205.988548] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5215.202913] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5235.018249] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 23:45:31 (1763354731) [ 5247.831068] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5249.463739] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5257.437312] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5267.961206] Lustre: Mounted lustre-client [ 5306.125049] LustreError: 174773:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6d1d01000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5306.134220] LustreError: 174773:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5306.159714] Lustre: Unmounted lustre-client [ 5324.162565] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5324.348730] Lustre: Mounted lustre-client [ 5356.879530] LustreError: 175430:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6c4092000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5356.889863] LustreError: 175430:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5356.893161] LustreError: 175430:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5356.919310] Lustre: Unmounted lustre-client [ 5374.743336] Key type lgssc unregistered [ 5374.861592] LNet: 175836:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5374.865213] LNetError: 175836:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5374.875885] LNet: Removed LNI 192.168.201.16@tcp [ 5375.121123] Key type .llcrypt unregistered [ 5375.122616] Key type ._llcrypt unregistered [ 5380.689580] Key type ._llcrypt registered [ 5380.691050] Key type .llcrypt registered [ 5381.202951] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 23:47:57 (1763354877) [ 5382.073268] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5382.079698] alg: No test for adler32 (adler32-zlib) [ 5382.961066] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 5383.053754] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 5384.648190] Key type lgssc registered [ 5385.072807] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5404.652347] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5406.194958] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5413.730624] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5425.654464] Lustre: Mounted lustre-client [ 5433.105532] LustreError: 179091:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6c4093800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5433.113497] LustreError: 179091:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5433.135283] Lustre: Unmounted lustre-client [ 5451.120040] Key type lgssc unregistered [ 5451.249757] LNet: 179493:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5451.253343] LNetError: 179493:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5451.269402] LNet: Removed LNI 192.168.201.16@tcp [ 5451.532294] Key type .llcrypt unregistered [ 5451.533769] Key type ._llcrypt unregistered [ 5456.331849] Key type ._llcrypt registered [ 5456.335148] Key type .llcrypt registered [ 5456.527937] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5456.534403] alg: No test for adler32 (adler32-zlib) [ 5457.410611] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 5457.507297] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 5459.104154] Key type lgssc registered [ 5459.546613] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5479.292205] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5480.671367] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5487.422245] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5497.853155] Lustre: Mounted lustre-client [ 5501.109915] LustreError: 182059:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6c4adc000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5501.129537] LustreError: 182059:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5501.158852] Lustre: Unmounted lustre-client [ 5519.095368] Key type lgssc unregistered [ 5519.222586] LNet: 182460:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5519.225469] LNetError: 182460:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5519.234673] LNet: Removed LNI 192.168.201.16@tcp [ 5519.493136] Key type .llcrypt unregistered [ 5519.494559] Key type ._llcrypt unregistered [ 5525.184110] Key type ._llcrypt registered [ 5525.185265] Key type .llcrypt registered [ 5525.736842] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 23:50:22 (1763355022) [ 5526.690758] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5526.699405] alg: No test for adler32 (adler32-zlib) [ 5527.578825] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 5527.673498] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 5529.272239] Key type lgssc registered [ 5529.733322] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5549.558658] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5551.058695] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5558.098194] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5569.525929] Lustre: Mounted lustre-client [ 5576.940494] LustreError: 185715:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6d220d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5576.947226] LustreError: 185715:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5576.970126] Lustre: Unmounted lustre-client [ 5602.930853] Key type lgssc unregistered [ 5603.056898] LNet: 186117:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5603.059580] LNetError: 186117:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5603.067847] LNet: Removed LNI 192.168.201.16@tcp [ 5603.325526] Key type .llcrypt unregistered [ 5603.327244] Key type ._llcrypt unregistered [ 5608.056889] Key type ._llcrypt registered [ 5608.058429] Key type .llcrypt registered [ 5608.281354] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5608.292734] alg: No test for adler32 (adler32-zlib) [ 5609.166259] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 5609.253661] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 5610.840294] Key type lgssc registered [ 5611.315336] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5630.052659] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5631.504474] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5638.238738] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5648.887062] Lustre: Mounted lustre-client [ 5651.892201] LustreError: 188688:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6d811e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5651.900306] LustreError: 188688:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5651.924149] Lustre: Unmounted lustre-client [ 5669.656660] Key type lgssc unregistered [ 5669.790658] LNet: 189090:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5669.793386] LNetError: 189090:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5669.802680] LNet: Removed LNI 192.168.201.16@tcp [ 5670.053411] Key type .llcrypt unregistered [ 5670.054957] Key type ._llcrypt unregistered [ 5675.581980] Key type ._llcrypt registered [ 5675.583200] Key type .llcrypt registered [ 5676.039408] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 23:52:52 (1763355172) [ 5683.489207] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5683.504700] alg: No test for adler32 (adler32-zlib) [ 5684.363362] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 5684.447442] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 5686.032178] Key type lgssc registered [ 5686.446757] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5715.979855] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5717.315275] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5724.959102] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5747.185683] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 23:54:03 (1763355243) [ 5757.670807] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5758.936973] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5765.655827] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5776.369263] Lustre: Mounted lustre-client [ 5778.314300] LustreError: 195589:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6c4096800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5778.326449] LustreError: 195589:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5778.354602] Lustre: Unmounted lustre-client [ 5796.159168] Key type lgssc unregistered [ 5796.280552] LNet: 195991:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5796.284214] LNetError: 195991:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5796.294668] LNet: Removed LNI 192.168.201.16@tcp [ 5796.537494] Key type .llcrypt unregistered [ 5796.538619] Key type ._llcrypt unregistered [ 5801.776288] Key type ._llcrypt registered [ 5801.777323] Key type .llcrypt registered [ 5802.247335] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 5802.827635] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 23:54:59 (1763355299) [ 5809.036995] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5809.061354] alg: No test for adler32 (adler32-zlib) [ 5809.930913] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 5810.026643] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 5811.616172] Key type lgssc registered [ 5812.015280] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5844.538454] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5845.750092] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5853.105769] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid [ 5859.029780] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid [ 5860.182860] Lustre: Mounted lustre-client [ 5866.479852] LustreError: 201515:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6c6db1800: lov tgt 1000 not cleaned! deathrow=0, lovrc=1 [ 5866.488540] LustreError: 201515:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5866.515594] Lustre: Unmounted lustre-client [ 5928.068670] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5929.286407] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5936.659928] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5957.645550] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 23:57:34 (1763355454) [ 5958.097042] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 5958.640541] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 23:57:35 (1763355455) [ 5976.315518] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5977.426928] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5999.287125] Key type lgssc unregistered [ 5999.405583] LNet: 207676:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5999.408503] LNetError: 207676:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5999.416617] LNet: Removed LNI 192.168.201.16@tcp [ 5999.630115] Key type .llcrypt unregistered [ 5999.631166] Key type ._llcrypt unregistered [ 6004.508327] Key type ._llcrypt registered [ 6004.509160] Key type .llcrypt registered [ 6004.943888] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 23:58:21 (1763355501) [ 6016.477273] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6016.487322] alg: No test for adler32 (adler32-zlib) [ 6017.337761] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6017.412271] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 6018.992159] Key type lgssc registered [ 6019.382297] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6033.571189] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6034.812282] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6041.060988] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6058.314750] Key type lgssc unregistered [ 6058.427422] LNet: 211298:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6058.430415] LNetError: 211298:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6058.438381] LNet: Removed LNI 192.168.201.16@tcp [ 6058.681570] Key type .llcrypt unregistered [ 6058.682749] Key type ._llcrypt unregistered [ 6063.788322] Key type ._llcrypt registered [ 6063.789565] Key type .llcrypt registered [ 6064.255440] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 23:59:20 (1763355560) [ 6065.110588] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6065.130186] alg: No test for adler32 (adler32-zlib) [ 6065.981856] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6066.065090] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 6067.648158] Key type lgssc registered [ 6068.014937] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6085.860066] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6087.045875] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6093.610892] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6105.071975] Lustre: Mounted lustre-client [ 6110.178778] Lustre: lustre-MDT0000-mdc-ffffa0b6d1357800: Connection to lustre-MDT0000 (at 192.168.201.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6115.299345] Lustre: lustre-MDT0001-mdc-ffffa0b6d1357800: Connection to lustre-MDT0001 (at 192.168.201.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6130.658415] LustreError: MGC192.168.201.116@tcp: Connection to MGS (at 192.168.201.116@tcp) was lost; in progress operations using this service will fail [ 6130.665651] Lustre: Evicted from MGS (at 192.168.201.116@tcp) after server handle changed from 0x3dc82b5d6c21c97e to 0x3dc82b5d6c21e2c2 [ 6130.670236] Lustre: MGC192.168.201.116@tcp: Connection restored to 192.168.201.116@tcp (at 192.168.201.116@tcp) [ 6149.231362] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6150.115317] LustreError: lustre-MDT0000-mdc-ffffa0b6d1357800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 6150.131446] Lustre: lustre-MDT0000-mdc-ffffa0b6d1357800: Connection restored to 192.168.201.116@tcp (at 192.168.201.116@tcp) [ 6150.784467] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 1 sec [ 6152.011745] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6152.564378] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6153.174092] LustreError: 215709:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6d1357800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6153.178486] LustreError: 215709:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6153.201178] Lustre: Unmounted lustre-client [ 6178.979496] Key type lgssc unregistered [ 6179.104546] LNet: 216128:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6179.107451] LNetError: 216128:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6179.117742] LNet: Removed LNI 192.168.201.16@tcp [ 6179.359689] Key type .llcrypt unregistered [ 6179.360956] Key type ._llcrypt unregistered [ 6184.658539] Key type ._llcrypt registered [ 6184.659734] Key type .llcrypt registered [ 6185.137095] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 00:01:21 (1763355681) [ 6185.871416] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6185.914035] alg: No test for adler32 (adler32-zlib) [ 6186.778514] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6186.864517] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 6188.448171] Key type lgssc registered [ 6188.841673] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6207.120664] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6208.338413] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6229.932191] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6231.153642] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6241.255423] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6247.791786] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6272.813391] Key type lgssc unregistered [ 6272.932445] LNet: 221226:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6272.935599] LNetError: 221226:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6272.946430] LNet: Removed LNI 192.168.201.16@tcp [ 6273.180402] Key type .llcrypt unregistered [ 6273.181320] Key type ._llcrypt unregistered [ 6287.294738] Key type ._llcrypt registered [ 6287.296349] Key type .llcrypt registered [ 6287.486829] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6287.492231] alg: No test for adler32 (adler32-zlib) [ 6288.340201] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6288.414835] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 6289.992126] Key type lgssc registered [ 6290.352320] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6318.727236] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6319.904257] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6327.232045] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6350.621451] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 00:04:07 (1763355847) [ 6387.048217] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6388.272440] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6395.827707] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6417.123866] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 00:05:13 (1763355913) [ 6419.529350] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 00:05:16 (1763355916) [ 6429.989611] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6431.226085] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6437.290325] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6447.609622] Lustre: Mounted lustre-client [ 6450.843892] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [ 6451.629258] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 6452.218464] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 6452.803521] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [ 6457.827079] Lustre: lustre-MDT0000-mdc-ffffa0b6c5a56800: Connection to lustre-MDT0000 (at 192.168.201.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6468.067583] LustreError: MGC192.168.201.116@tcp: Connection to MGS (at 192.168.201.116@tcp) was lost; in progress operations using this service will fail [ 6468.073915] Lustre: Evicted from MGS (at 192.168.201.116@tcp) after server handle changed from 0xbdda31d3402acbab to 0xbdda31d3402ace2f [ 6468.078592] Lustre: MGC192.168.201.116@tcp: Connection restored to 192.168.201.116@tcp (at 192.168.201.116@tcp) [ 6471.886733] Lustre: lustre-MDT0000-mdc-ffffa0b6c5a56800: Connection restored to 192.168.201.116@tcp (at 192.168.201.116@tcp) [ 6473.016627] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6473.519639] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6475.329566] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [ 6478.307300] Lustre: lustre-MDT0000-mdc-ffffa0b6c5a56800: Connection to lustre-MDT0000 (at 192.168.201.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6493.666355] LustreError: MGC192.168.201.116@tcp: Connection to MGS (at 192.168.201.116@tcp) was lost; in progress operations using this service will fail [ 6493.671301] Lustre: Evicted from MGS (at 192.168.201.116@tcp) after server handle changed from 0xbdda31d3402ace2f to 0xbdda31d3402ad131 [ 6493.673989] Lustre: MGC192.168.201.116@tcp: Connection restored to 192.168.201.116@tcp (at 192.168.201.116@tcp) [ 6496.003872] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6496.502991] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6498.013374] LustreError: 234809:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6c5a56800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6498.021213] LustreError: 234809:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6498.037140] Lustre: Unmounted lustre-client [ 6531.265547] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6532.551419] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6538.935708] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6549.492737] Lustre: Mounted lustre-client [ 6549.844316] LustreError: 236426:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6d2209000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6549.850725] LustreError: 236426:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6549.853363] LustreError: 236426:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 6549.877087] Lustre: Unmounted lustre-client [ 6567.384502] Key type lgssc unregistered [ 6567.500708] LNet: 236828:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6567.503065] LNetError: 236828:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6567.510727] LNet: Removed LNI 192.168.201.16@tcp [ 6567.766704] Key type .llcrypt unregistered [ 6567.767944] Key type ._llcrypt unregistered [ 6573.090588] Key type ._llcrypt registered [ 6573.091456] Key type .llcrypt registered [ 6573.577349] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 00:07:50 (1763356070) [ 6575.009979] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6575.018430] alg: No test for adler32 (adler32-zlib) [ 6575.871137] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6575.955243] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 6577.544154] Key type lgssc registered [ 6578.006827] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6596.470624] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6597.725445] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6604.133873] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6614.513472] Lustre: Mounted lustre-client [ 6617.583802] LustreError: 239992:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6c5a54800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6617.591747] LustreError: 239992:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6617.616165] Lustre: Unmounted lustre-client [ 6650.657687] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6651.946310] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6658.225334] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6668.787394] Lustre: Mounted lustre-client [ 6675.024931] LustreError: 241587:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6c4097000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6675.034383] LustreError: 241587:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6675.036252] LustreError: 241587:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 6675.058263] Lustre: Unmounted lustre-client [ 6708.593953] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6709.755335] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6715.861511] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6726.643869] Lustre: Mounted lustre-client [ 6727.029789] LustreError: 243200:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6c4097800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6727.035813] LustreError: 243200:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6727.037466] LustreError: 243200:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 6727.059247] Lustre: Unmounted lustre-client [ 6744.567720] Key type lgssc unregistered [ 6744.683522] LNet: 243603:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6744.685789] LNetError: 243603:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6744.694705] LNet: Removed LNI 192.168.201.16@tcp [ 6744.922950] Key type .llcrypt unregistered [ 6744.924515] Key type ._llcrypt unregistered [ 6750.461119] Key type ._llcrypt registered [ 6750.462108] Key type .llcrypt registered [ 6750.924816] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 00:10:47 (1763356247) [ 6751.980290] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6752.010064] alg: No test for adler32 (adler32-zlib) [ 6752.860925] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6752.935827] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 6754.520158] Key type lgssc registered [ 6754.913730] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6771.461657] Key type lgssc unregistered [ 6771.587423] LNet: 245913:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6771.591345] LNetError: 245913:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6771.604307] LNet: Removed LNI 192.168.201.16@tcp [ 6771.843505] Key type .llcrypt unregistered [ 6771.844616] Key type ._llcrypt unregistered [ 6781.909873] Key type ._llcrypt registered [ 6781.912679] Key type .llcrypt registered [ 6782.106662] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6782.113780] alg: No test for adler32 (adler32-zlib) [ 6782.982212] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6783.067325] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 6784.648125] Key type lgssc registered [ 6785.049847] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6814.122336] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6815.289559] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6822.715797] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6847.005384] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [ 6847.556721] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 00:12:24 (1763356344) [ 6857.525639] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6858.699186] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6864.451130] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6871.556687] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6872.726395] Lustre: Mounted lustre-client [ 6878.180517] Lustre: lustre-OST0001-osc-ffffa0b6c4092800: Connection to lustre-OST0001 (at 192.168.201.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6880.363416] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6890.601211] LustreError: 252395:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 6890.618114] Lustre: Unmounted lustre-client [ 6914.267971] Key type lgssc unregistered [ 6914.390649] LNet: 252799:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6914.393535] LNetError: 252799:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6914.404143] LNet: Removed LNI 192.168.201.16@tcp [ 6914.657760] Key type .llcrypt unregistered [ 6914.659955] Key type ._llcrypt unregistered [ 6929.547747] Key type ._llcrypt registered [ 6929.550955] Key type .llcrypt registered [ 6929.776521] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6929.781871] alg: No test for adler32 (adler32-zlib) [ 6930.651982] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6930.737213] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 6932.328169] Key type lgssc registered [ 6932.776340] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6963.096572] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6964.350425] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6971.907232] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7003.352122] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 00:14:59 (1763356499) [ 7016.773842] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7018.033299] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7029.730067] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 00:15:26 (1763356526) [ 7039.437726] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7040.530566] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7046.273235] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7047.435533] Lustre: Mounted lustre-client [ 7052.325558] LustreError: 260591:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6c6c30800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7052.331474] LustreError: 260591:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7052.350211] Lustre: Unmounted lustre-client [ 7100.572309] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7101.753619] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7137.835220] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7148.532373] Lustre: Mounted lustre-client [ 7148.965139] LustreError: 262683:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6d0994800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7148.973482] LustreError: 262683:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7148.975016] LustreError: 262683:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 7148.997975] Lustre: Unmounted lustre-client [ 7158.575144] Key type lgssc unregistered [ 7158.697463] LNet: 263084:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7158.699550] LNetError: 263084:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7158.706972] LNet: Removed LNI 192.168.201.16@tcp [ 7158.925982] Key type .llcrypt unregistered [ 7158.927453] Key type ._llcrypt unregistered [ 7168.708689] Key type ._llcrypt registered [ 7168.709726] Key type .llcrypt registered [ 7168.932943] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7168.939712] alg: No test for adler32 (adler32-zlib) [ 7169.800165] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 7169.886178] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 7171.472170] Key type lgssc registered [ 7171.904587] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7201.667651] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7202.904829] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7210.449902] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7231.675217] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [ 7232.189609] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 00:18:48 (1763356728) [ 7241.391668] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7242.501485] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7248.571023] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7250.655071] Lustre: Mounted lustre-client [ 7251.327253] LustreError: 269113:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6d0990800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7251.334712] LustreError: 269113:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7251.355163] Lustre: Unmounted lustre-client [ 7268.647879] Key type lgssc unregistered [ 7268.773838] LNet: 269515:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7268.776417] LNetError: 269515:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7268.786228] LNet: Removed LNI 192.168.201.16@tcp [ 7269.043733] Key type .llcrypt unregistered [ 7269.044839] Key type ._llcrypt unregistered [ 7274.420024] Key type ._llcrypt registered [ 7274.421084] Key type .llcrypt registered [ 7274.937593] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [ 7275.535395] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 00:19:32 (1763356772) [ 7278.462573] Key type .llcrypt unregistered [ 7278.464445] Key type ._llcrypt unregistered [ 7281.488206] Key type ._llcrypt registered [ 7281.490205] Key type .llcrypt registered [ 7281.646375] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7281.651447] alg: No test for adler32 (adler32-zlib) [ 7282.532296] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 7282.640407] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 7284.240193] Key type lgssc registered [ 7284.752539] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7303.329740] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7308.993091] Lustre: Mounted lustre-client [ 7309.460374] LustreError: 272520:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6d84d0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7309.467674] LustreError: 272520:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7309.489770] Lustre: Unmounted lustre-client [ 7318.774570] Key type lgssc unregistered [ 7318.899395] LNet: 272921:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7318.902638] LNetError: 272921:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7318.910288] LNet: Removed LNI 192.168.201.16@tcp [ 7319.178111] Key type .llcrypt unregistered [ 7319.179470] Key type ._llcrypt unregistered [ 7324.738115] Key type ._llcrypt registered [ 7324.739144] Key type .llcrypt registered [ 7325.252025] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 00:20:21 (1763356821) [ 7326.137215] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7326.150190] alg: No test for adler32 (adler32-zlib) [ 7327.023217] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 7327.125584] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 7328.720187] Key type lgssc registered [ 7329.161847] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7388.861462] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7398.879593] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7400.142637] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7401.305769] Lustre: Mounted lustre-client [ 7401.780314] LustreError: 275959:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6d1350000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7401.787711] LustreError: 275959:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7401.809252] Lustre: Unmounted lustre-client [ 7419.320729] Key type lgssc unregistered [ 7419.458521] LNet: 276361:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7419.461420] LNetError: 276361:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7419.471186] LNet: Removed LNI 192.168.201.16@tcp [ 7419.746237] Key type .llcrypt unregistered [ 7419.747908] Key type ._llcrypt unregistered [ 7425.497548] Key type ._llcrypt registered [ 7425.499168] Key type .llcrypt registered [ 7425.995579] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 00:22:02 (1763356922) [ 7426.979873] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7427.016465] alg: No test for adler32 (adler32-zlib) [ 7427.892821] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 7427.995612] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 7429.592247] Key type lgssc registered [ 7430.051824] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7447.919524] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7449.141737] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7455.485143] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7465.968174] Lustre: Mounted lustre-client [ 7471.075444] Lustre: lustre-MDT0000-mdc-ffffa0b6c6db2000: Connection to lustre-MDT0000 (at 192.168.201.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7473.657264] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7473.669608] LustreError: 279420:0:(file.c:6099:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [ 7473.777749] LustreError: 279439:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6c6db2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7473.779764] LustreError: MGC192.168.201.116@tcp: Connection to MGS (at 192.168.201.116@tcp) was lost; in progress operations using this service will fail [ 7473.784775] LustreError: 279439:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7473.800190] Lustre: Unmounted lustre-client [ 7484.517724] Key type lgssc unregistered [ 7484.625425] LNet: 279831:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7484.629650] LNetError: 279831:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7484.636316] LNet: Removed LNI 192.168.201.16@tcp [ 7484.869404] Key type .llcrypt unregistered [ 7484.870439] Key type ._llcrypt unregistered [ 7490.216441] Key type ._llcrypt registered [ 7490.217292] Key type .llcrypt registered [ 7490.673564] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 00:23:07 (1763356987) [ 7491.386695] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7491.401045] alg: No test for adler32 (adler32-zlib) [ 7492.258041] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 7492.339653] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 7493.928137] Key type lgssc registered [ 7494.312766] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7511.315574] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7512.577145] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7518.575393] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7534.066386] Lustre: Mounted lustre-client [ 7539.171750] Lustre: lustre-MDT0001-mdc-ffffa0b6d1354000: Connection to lustre-MDT0001 (at 192.168.201.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7541.863530] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7542.235631] LustreError: 282919:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6d1354000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7542.243942] LustreError: 282919:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7542.262714] Lustre: Unmounted lustre-client [ 7553.145214] Key type lgssc unregistered [ 7553.269670] LNet: 283311:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7553.272261] LNetError: 283311:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7553.284749] LNet: Removed LNI 192.168.201.16@tcp [ 7553.524689] Key type .llcrypt unregistered [ 7553.525740] Key type ._llcrypt unregistered [ 7559.106038] Key type ._llcrypt registered [ 7559.107120] Key type .llcrypt registered [ 7559.602654] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 00:24:16 (1763357056) [ 7565.849904] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7565.856801] alg: No test for adler32 (adler32-zlib) [ 7566.713797] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 7566.805467] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 7568.392157] Key type lgssc registered [ 7568.758573] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7606.287071] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7612.892055] Lustre: Mounted lustre-client [ 7628.260793] Lustre: lustre-MDT0001-mdc-ffffa0b6c6db0800: Connection to lustre-MDT0001 (at 192.168.201.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7633.530870] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7643.815664] LustreError: 288645:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 7643.832453] Lustre: Unmounted lustre-client [ 7661.098055] Key type lgssc unregistered [ 7661.212550] LNet: 289045:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7661.214713] LNetError: 289045:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7661.224883] LNet: Removed LNI 192.168.201.16@tcp [ 7661.492575] Key type .llcrypt unregistered [ 7661.493526] Key type ._llcrypt unregistered [ 7667.056747] Key type ._llcrypt registered [ 7667.058619] Key type .llcrypt registered [ 7667.577941] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 00:26:04 (1763357164) [ 7668.167224] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [ 7668.756792] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 00:26:05 (1763357165) [ 7669.297888] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [ 7669.875590] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 00:26:06 (1763357166) [ 7670.440610] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [ 7671.021421] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 00:26:07 (1763357167) [ 7671.619128] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [ 7672.236235] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 00:26:08 (1763357168) [ 7672.776895] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [ 7673.332641] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 00:26:09 (1763357169) [ 7676.307320] Key type .llcrypt unregistered [ 7676.308457] Key type ._llcrypt unregistered [ 7678.637938] Key type ._llcrypt registered [ 7678.638919] Key type .llcrypt registered [ 7678.837457] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7678.842689] alg: No test for adler32 (adler32-zlib) [ 7679.699255] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 7679.795096] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 7681.376158] Key type lgssc registered [ 7681.764420] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7708.748868] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7710.116816] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7717.747387] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7718.946922] Lustre: Mounted lustre-client [ 7719.435718] LustreError: 294623:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6c4097800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7719.443152] LustreError: 294623:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7719.471126] Lustre: Unmounted lustre-client [ 7742.527757] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 00:27:19 (1763357239) [ 7752.448728] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7753.639801] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7759.503733] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7775.217679] Lustre: Mounted lustre-client [ 7775.536519] LustreError: 296735:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffffa0b6c5c86800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7775.542760] LustreError: 296735:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7775.545011] LustreError: 296735:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 7775.565170] Lustre: Unmounted lustre-client [ 7802.711652] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 00:28:19 (1763357299) [ 7815.968333] LNet: 1 local NIs in recovery (showing 1): 192.168.201.16@tcp [ 7830.779772] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7831.954988] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7837.788424] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7869.958822] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 00:29:26 (1763357366) [ 7873.802061] Key type lgssc unregistered [ 7873.911664] LNet: 302173:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7873.913957] LNetError: 302173:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7890.342781] LNet: Removed LNI 192.168.201.16@tcp [ 7890.583451] Key type .llcrypt unregistered [ 7890.585242] Key type ._llcrypt unregistered [ 8024.612739] Key type ._llcrypt registered [ 8024.614146] Key type .llcrypt registered [ 8024.813673] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8024.819297] alg: No test for adler32 (adler32-zlib) [ 8025.668665] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 8025.743548] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 8027.320209] Key type lgssc registered [ 8027.930762] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8066.061083] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8067.376148] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8075.931254] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8095.641989] Key type lgssc unregistered [ 8095.757721] LNet: 305766:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8095.759954] LNetError: 305766:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8095.769460] LNet: Removed LNI 192.168.201.16@tcp [ 8095.985442] Key type .llcrypt unregistered [ 8095.986416] Key type ._llcrypt unregistered [ 8110.780248] Key type ._llcrypt registered [ 8110.781609] Key type .llcrypt registered [ 8111.265203] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 00:33:27 (1763357607) [ 8118.502815] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8118.509477] alg: No test for adler32 (adler32-zlib) [ 8119.371179] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 8119.458452] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 8121.056124] Key type lgssc registered [ 8121.535134] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8139.925801] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 00:33:56 (1763357636) [ 8143.742620] Key type lgssc unregistered [ 8143.869641] LNet: 309307:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8143.871771] LNetError: 309307:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8143.879768] LNet: Removed LNI 192.168.201.16@tcp [ 8144.122449] Key type .llcrypt unregistered [ 8144.123411] Key type ._llcrypt unregistered [ 8147.624156] Key type ._llcrypt registered [ 8147.625064] Key type .llcrypt registered [ 8147.843577] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8147.848071] alg: No test for adler32 (adler32-zlib) [ 8148.698931] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 8148.778673] LNet: Added LNI 192.168.201.16@tcp [8/256/0/180] [ 8150.360184] Key type lgssc registered [ 8150.789045] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8174.030936] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8175.343577] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8182.487456] Lustre: DEBUG MARKER: oleg116-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid