[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.16.2-1.fc38 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 457595829 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 0x000f5b50-0x000f5b5f] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F5970 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE1BB7 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE1A53 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 001A13 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE1AC7 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE1B57 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE1B8F 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe1a53-0xbffe1ac6] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe1a52] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe1ac7-0xbffe1b56] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe1b57-0xbffe1b8e] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe1b8f-0xbffe1bb6] [ 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.001012] APIC: Switch to symmetric I/O mode setup [ 0.003260] x2apic enabled [ 0.004008] Switched APIC routing to physical x2apic. [ 0.005011] kvm-guest: setup PV IPIs [ 0.007717] ..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.008018] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009012] pid_max: default: 32768 minimum: 301 [ 0.011062] LSM: Security Framework initializing [ 0.012050] Yama: becoming mindful. [ 0.013036] SELinux: Initializing. [ 0.014088] *** VALIDATE selinux *** [ 0.022531] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026850] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.028121] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029118] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030114] *** VALIDATE tmpfs *** [ 0.031560] *** VALIDATE proc *** [ 0.033095] *** VALIDATE cgroup *** [ 0.034008] *** VALIDATE cgroup2 *** [ 0.035266] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.036153] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.037008] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.038028] Spectre V2 : User space: Vulnerable [ 0.039007] Speculative Store Bypass: Vulnerable [ 0.042165] debug: unmapping init [mem 0xffffffffb2259000-0xffffffffb2260fff] [ 0.044922] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.045673] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.046022] ... version: 2 [ 0.047011] ... bit width: 48 [ 0.048011] ... generic registers: 4 [ 0.049010] ... value mask: 0000ffffffffffff [ 0.050012] ... max period: 00007fffffffffff [ 0.051024] ... fixed-purpose events: 3 [ 0.052011] ... event mask: 000000070000000f [ 0.053281] rcu: Hierarchical SRCU implementation. [ 0.055388] smp: Bringing up secondary CPUs ... [ 0.056511] x86: Booting SMP configuration: [ 0.057031] .... node #0, CPUs: #1 #2 #3 [ 0.062147] smp: Brought up 1 node, 4 CPUs [ 0.064024] smpboot: Max logical packages: 1 [ 0.065028] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.283135] node 0 deferred pages initialised in 215ms [ 0.288010] devtmpfs: initialized [ 0.289251] x86/mm: Memory block size: 128MB [ 0.291783] gcov: version magic: 0x41383552 [ 0.294279] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.295063] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.297331] pinctrl core: initialized pinctrl subsystem [ 0.300196] [ 0.300771] ************************************************************* [ 0.301011] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.302011] ** ** [ 0.303014] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.306012] ** ** [ 0.308009] ** This means that this kernel is built to expose internal ** [ 0.310012] ** IOMMU data structures, which may compromise security on ** [ 0.312012] ** your system. ** [ 0.315013] ** ** [ 0.317013] ** If you see this message and you are not debugging the ** [ 0.320014] ** kernel, report this immediately to your vendor! ** [ 0.322010] ** ** [ 0.323012] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.325018] ************************************************************* [ 0.328347] NET: Registered protocol family 16 [ 0.330509] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.334062] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.336060] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.339121] cpuidle: using governor menu [ 0.340699] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.344464] PCI: Using configuration type 1 for base access [ 0.346156] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.356113] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.358039] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.362066] cryptd: max_cpu_qlen set to 1000 [ 0.365283] ACPI: Added _OSI(Module Device) [ 0.367019] ACPI: Added _OSI(Processor Device) [ 0.369022] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.371016] ACPI: Added _OSI(Processor Aggregator Device) [ 0.375584] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.381149] ACPI: Interpreter enabled [ 0.382058] ACPI: PM: (supports S0 S3 S4 S5) [ 0.383015] ACPI: Using IOAPIC for interrupt routing [ 0.385095] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.388392] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.398156] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.400046] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.403021] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.407104] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.412196] acpiphp: Slot [2] registered [ 0.413127] acpiphp: Slot [3] registered [ 0.415087] acpiphp: Slot [4] registered [ 0.416111] acpiphp: Slot [5] registered [ 0.418126] acpiphp: Slot [6] registered [ 0.419113] acpiphp: Slot [7] registered [ 0.421100] acpiphp: Slot [8] registered [ 0.422104] acpiphp: Slot [9] registered [ 0.423090] acpiphp: Slot [10] registered [ 0.425134] acpiphp: Slot [11] registered [ 0.427103] acpiphp: Slot [12] registered [ 0.429143] acpiphp: Slot [13] registered [ 0.430258] acpiphp: Slot [14] registered [ 0.432176] acpiphp: Slot [15] registered [ 0.434149] acpiphp: Slot [16] registered [ 0.435131] acpiphp: Slot [17] registered [ 0.436115] acpiphp: Slot [18] registered [ 0.438139] acpiphp: Slot [19] registered [ 0.439187] acpiphp: Slot [20] registered [ 0.440000] acpiphp: Slot [21] registered [ 0.440000] acpiphp: Slot [22] registered [ 0.443160] acpiphp: Slot [23] registered [ 0.444134] acpiphp: Slot [24] registered [ 0.446113] acpiphp: Slot [25] registered [ 0.447180] acpiphp: Slot [26] registered [ 0.449128] acpiphp: Slot [27] registered [ 0.450142] acpiphp: Slot [28] registered [ 0.452150] acpiphp: Slot [29] registered [ 0.454152] acpiphp: Slot [30] registered [ 0.456155] acpiphp: Slot [31] registered [ 0.457132] PCI host bridge to bus 0000:00 [ 0.459032] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.461048] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.463052] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.466027] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.468028] pci_bus 0000:00: root bus resource [mem 0x180000000-0x1ffffffff window] [ 0.470032] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.472172] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.477373] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.481000] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.489933] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.494013] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.497019] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.499018] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.502022] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.505757] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.508697] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.511059] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.513740] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.518022] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.529015] pci 0000:00:02.0: reg 0x20: [mem 0xfebf4000-0xfebf7fff 64bit pref] [ 0.533015] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.539202] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.544014] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.549016] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.563013] pci 0000:00:05.0: reg 0x20: [mem 0xfebf8000-0xfebfbfff 64bit pref] [ 0.574403] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.581018] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.586014] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.599020] pci 0000:00:06.0: reg 0x20: [mem 0xfebfc000-0xfebfffff 64bit pref] [ 0.610313] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.612401] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.614570] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.616675] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.619244] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.625116] iommu: Default domain type: Passthrough [ 0.626000] SCSI subsystem initialized [ 0.626000] ACPI: bus type USB registered [ 0.627126] usbcore: registered new interface driver usbfs [ 0.629080] usbcore: registered new interface driver hub [ 0.631123] usbcore: registered new device driver usb [ 0.633142] pps_core: LinuxPPS API ver. 1 registered [ 0.635011] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.638083] PTP clock support registered [ 0.640104] EDAC MC: Ver: 3.0.0 [ 0.642120] PCI: Using ACPI for IRQ routing [ 0.643756] NetLabel: Initializing [ 0.645009] NetLabel: domain hash size = 128 [ 0.646010] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.648073] NetLabel: unlabeled traffic allowed by default [ 0.650114] vgaarb: loaded [ 0.651249] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.653015] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.659020] clocksource: Switched to clocksource kvm-clock [ 0.761439] VFS: Disk quotas dquot_6.6.0 [ 0.762829] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.765428] *** VALIDATE ramfs *** [ 0.766584] *** VALIDATE hugetlbfs *** [ 0.768633] pnp: PnP ACPI init [ 0.770881] pnp: PnP ACPI: found 6 devices [ 0.787138] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.790149] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.792234] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.794301] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.796605] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.798323] pci_bus 0000:00: resource 8 [mem 0x180000000-0x1ffffffff window] [ 0.800946] NET: Registered protocol family 2 [ 0.803107] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.807942] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.811568] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.817453] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.821102] TCP: Hash tables configured (established 65536 bind 65536) [ 0.823792] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.827165] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.829957] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.832787] NET: Registered protocol family 1 [ 0.834933] RPC: Registered named UNIX socket transport module. [ 0.837182] RPC: Registered udp transport module. [ 0.838943] RPC: Registered tcp transport module. [ 0.840678] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.843138] NET: Registered protocol family 44 [ 0.844860] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.847125] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.849421] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.851450] PCI: CLS 0 bytes, default 64 [ 0.853171] Unpacking initramfs... [ 2.237596] debug: unmapping init [mem 0xffff8cce7cc64000-0xffff8cce7ffcffff] [ 2.241027] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.243359] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.246197] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.746888] Initialise system trusted keyrings [ 2.748701] Key type blacklist registered [ 2.750725] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.760243] zbud: loaded [ 2.764199] *** VALIDATE nfs *** [ 2.765503] *** VALIDATE nfs4 *** [ 2.767144] pstore: using deflate compression [ 2.770228] Platform Keyring initialized [ 2.879114] NET: Registered protocol family 38 [ 2.881126] Key type asymmetric registered [ 2.882765] Asymmetric key parser 'x509' registered [ 2.884788] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.888022] io scheduler mq-deadline registered [ 2.889429] io scheduler kyber registered [ 2.890684] io scheduler bfq registered [ 2.892547] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.894972] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.897647] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.900213] ACPI: Power Button [PWRF] [ 2.986499] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.082453] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.184049] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.212438] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.244309] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.248646] Non-volatile memory driver v1.3 [ 3.249895] Linux agpgart interface v0.103 [ 3.282811] virtio_blk virtio1: [vda] 133840 512-byte logical blocks (68.5 MB/65.4 MiB) [ 3.285103] vda: detected capacity change from 0 to 68526080 [ 3.299424] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.302070] vdb: detected capacity change from 0 to 1073741824 [ 3.309968] libphy: Fixed MDIO Bus: probed [ 3.319610] usbcore: registered new interface driver usbserial_generic [ 3.321656] usbserial: USB Serial support registered for generic [ 3.323347] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.326676] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.327893] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.329738] mousedev: PS/2 mouse device common for all mice [ 3.332337] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.333342] rtc_cmos 00:05: RTC can wake from S4 [ 3.338082] rtc_cmos 00:05: registered as rtc0 [ 3.339524] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.341588] intel_pstate: CPU model not supported [ 3.343175] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.346396] hid: raw HID events driver (C) Jiri Kosina [ 3.348078] usbcore: registered new interface driver usbhid [ 3.349337] usbhid: USB HID core driver [ 3.349551] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.350325] drop_monitor: Initializing network drop monitor service [ 3.354418] Initializing XFRM netlink socket [ 3.355947] NET: Registered protocol family 10 [ 3.359984] Segment Routing with IPv6 [ 3.361177] NET: Registered protocol family 17 [ 3.362680] mpls_gso: MPLS GSO support [ 3.368140] RAS: Correctable Errors collector initialized. [ 3.370237] AVX version of gcm_enc/dec engaged. [ 3.371680] AES CTR mode by8 optimization enabled [ 3.461522] sched_clock: Marking stable (3461493825, 0)->(4367503338, -906009513) [ 3.465086] registered taskstats version 1 [ 3.467989] Loading compiled-in X.509 certificates [ 3.470541] zswap: loaded using pool lzo/zbud [ 3.495242] Key type big_key registered [ 3.506254] Key type encrypted registered [ 3.507347] ima: No TPM chip found, activating TPM-bypass! [ 3.509591] ima: Allocated hash algorithm: sha1 [ 3.511249] ima: No architecture policies found [ 3.513173] evm: Initialising EVM extended attributes: [ 3.515289] evm: security.selinux [ 3.516638] evm: security.ima [ 3.517857] evm: security.capability [ 3.518980] evm: HMAC attrs: 0x1 [ 3.521531] rtc_cmos 00:05: setting system clock to 2025-11-17 03:46:20 UTC (1763351180) [ 3.527524] debug: unmapping init [mem 0xffffffffb3203000-0xffffffffb33fffff] [ 3.530583] debug: unmapping init [mem 0xffffffffb1f82000-0xffffffffb2258fff] [ 3.539130] Write protecting the kernel read-only data: 28672k [ 3.542446] debug: unmapping init [mem 0xffffffffb0603000-0xffffffffb07fffff] [ 3.544946] debug: unmapping init [mem 0xffffffffb0f14000-0xffffffffb0ffffff] [ 3.574851] 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.583419] systemd[1]: Detected virtualization kvm. [ 3.585445] systemd[1]: Detected architecture x86-64. [ 3.590698] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.615880] systemd[1]: No hostname configured. [ 3.617784] systemd[1]: Set hostname to . [ 3.619777] random: systemd: uninitialized urandom read (16 bytes read) [ 3.622631] systemd[1]: Initializing machine ID from random generator. [ 3.741800] random: systemd: uninitialized urandom read (16 bytes read) [ 3.744461] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 3.748545] random: systemd: uninitialized urandom read (16 bytes read) [ 3.751928] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 3.756364] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ OK ] Reached target Swap. [ OK ] Reached target Initrd Root Device. Starting Apply Kernel Variables... [ OK ] Reached target Timers. Starting Create list of required st…ce nodes for the current kernel... Starting Setup Virtual Console... [ OK ] Reached target Local File Systems. [ OK ] Listening on udev Control Socket. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Sockets. Starting Journal Service... Starting Create Volatile Files and Directories... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Started Memstrack Anylazing Service. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Setup Virtual Console. [ OK ] Started Create Volatile Files and Directories. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Journal Service. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.338994] device-mapper: uevent: version 1.0.3 [ 4.341149] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 4.988653] virtio_net virtio0 ens2: renamed from eth0 [ 5.034672] scsi host0: ata_piix [ 5.067945] scsi host1: ata_piix [ 5.069414] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.072042] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.643695] dracut-initqueue[578]: RTNETLINK answers: File exists [ 10.023500] random: crng init done [ 10.024589] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 10.284273] 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 target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ 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 Initrd Root Device. [ 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 target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Apply Kernel Variables. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.347491] printk: systemd: 24 output lines suppressed due to ratelimiting [ 11.596731] SELinux: Disabled at runtime. [ 11.655429] 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.664932] systemd[1]: Detected virtualization kvm. [ 11.666938] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.169648] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.173069] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.177762] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.182029] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.185259] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.192712] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.200914] systemd[1]: Mounting Huge Pages File System... Mounting Huge Pages File System... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Listening on Process Core Dump Socket. [ OK ] Listening on udev Control Socket. Starting Remount Root and Kernel File Systems... Activating swap /dev/disk/by-label/SWAP... [ OK ] Reached target rpc_pipefs.target. [ OK ] Listening on udev Kernel Socket. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Created slice system-getty.slice. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ 12.301435] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Starting Apply Kernel Variables... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Stopped target Initrd Root File System. Mounting POSIX Message Queue File System... Starting udev Coldplug all Devices... Mounting Kernel Debug File System... [ OK ] Reached target Paths. [ OK ] Started Journal Service. [ OK ] Mounted Huge Pages File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Mounted Kernel Debug File System. [ 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. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 12.614321] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 12.883366] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 12.929532] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.003402] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.026968] EDAC sbridge: Ver: 1.1.2 [ 14.220219] Key type dns_resolver registered [ 14.519398] NFS: Registering the id_resolver key type [ 14.521334] Key type id_resolver registered [ 14.522928] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... Starting Mark the need to relabel after reboot... 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 dnf makecache --timer. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Reached target sshd-keygen.target. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... Starting Login Service... Starting Restore /run/initramfs on shutdown... [ OK ] Started irqbalance daemon. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Network Manager Wait Online... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... [ 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 oleg229-client login: [ 45.636722] libcfs: loading out-of-tree module taints kernel. [ 45.649926] Key type ._llcrypt registered [ 45.651530] Key type .llcrypt registered [ 45.925570] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 45.932592] alg: No test for adler32 (adler32-zlib) [ 46.958032] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 47.320937] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 48.960218] Key type lgssc registered [ 49.682659] Lustre: Echo OBD driver; http://www.lustre.org/ [ 154.338051] hrtimer: interrupt took 2906017 ns [ 186.347332] Lustre: Mounted lustre-client [ 190.192295] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 205.131145] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing check_logdir /tmp/testlogs/ [ 208.824920] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing yml_node [ 211.936411] Lustre: lustre-OST0000-osc-ffff8ccee0117800: disconnect after 23s idle [ 213.163102] Lustre: DEBUG MARKER: Client: 2.16.61.41 [ 215.267667] Lustre: DEBUG MARKER: MDS: 2.16.61.41 [ 217.968763] Lustre: DEBUG MARKER: OSS: 2.16.61.41 [ 219.382994] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Sun Nov 16 22:49:55 EST 2025 [ 238.581226] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 239.989267] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 241.962250] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 252.213345] LustreError: 8984:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 252.285642] Lustre: Unmounted lustre-client [ 357.704823] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 360.586487] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 379.272366] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 419.258349] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 22:53:14 (1763351594) [ 421.498630] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 423.021804] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 22:53:18 (1763351598) [ 445.484474] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 448.486173] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 463.249172] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 463.820553] Lustre: Mounted lustre-client [ 466.951318] LustreError: 14425:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ccec346c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 466.967834] LustreError: 14425:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 466.975878] LustreError: 14425:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 467.031324] Lustre: Unmounted lustre-client [ 491.627430] Key type lgssc unregistered [ 491.847537] LNet: 14830:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 491.856021] LNetError: 14830:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 491.883467] LNet: Removed LNI 192.168.202.29@tcp [ 492.537174] Key type .llcrypt unregistered [ 492.547562] Key type ._llcrypt unregistered [ 506.362851] Key type ._llcrypt registered [ 506.364516] Key type .llcrypt registered [ 507.598987] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 22:54:43 (1763351683) [ 509.179863] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 509.191786] alg: No test for adler32 (adler32-zlib) [ 510.308644] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 510.509147] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 512.144225] Key type lgssc registered [ 513.296348] Lustre: Echo OBD driver; http://www.lustre.org/ [ 576.312206] Lustre: Mounted lustre-client [ 661.213444] LustreError: 18158:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ccec4a7b800: lov tgt 1990 not cleaned! deathrow=0, lovrc=1 [ 661.242504] LustreError: 18158:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 661.284248] Lustre: Unmounted lustre-client [ 683.850121] Key type lgssc unregistered [ 684.026714] LNet: 18621:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 684.030665] LNetError: 18621:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 684.043694] LNet: Removed LNI 192.168.202.29@tcp [ 684.596112] Key type .llcrypt unregistered [ 684.597813] Key type ._llcrypt unregistered [ 692.103153] Key type ._llcrypt registered [ 692.104566] Key type .llcrypt registered [ 693.230554] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 22:57:48 (1763351868) [ 695.311692] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 695.323264] alg: No test for adler32 (adler32-zlib) [ 696.312596] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 696.513539] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 698.184228] Key type lgssc registered [ 698.966609] Lustre: Echo OBD driver; http://www.lustre.org/ [ 732.886624] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 735.948185] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 751.781223] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 763.943959] Lustre: Mounted lustre-client [ 770.890690] LustreError: 21724:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ccec2e12000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 770.903431] LustreError: 21724:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 770.935135] Lustre: Unmounted lustre-client [ 801.177250] Key type lgssc unregistered [ 801.335968] LNet: 22127:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 801.341642] LNetError: 22127:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 801.353956] LNet: Removed LNI 192.168.202.29@tcp [ 801.779909] Key type .llcrypt unregistered [ 801.781651] Key type ._llcrypt unregistered [ 809.016734] Key type ._llcrypt registered [ 809.018206] Key type .llcrypt registered [ 810.003169] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 22:59:45 (1763351985) [ 811.386186] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 811.441224] alg: No test for adler32 (adler32-zlib) [ 812.462343] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 812.627622] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 814.272175] Key type lgssc registered [ 815.041831] Lustre: Echo OBD driver; http://www.lustre.org/ [ 847.760830] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 850.376622] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 862.917895] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 864.273878] Lustre: Mounted lustre-client [ 871.793758] LustreError: 24821:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ccec9233800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 871.811975] LustreError: 24821:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 871.863268] Lustre: Unmounted lustre-client [ 901.540099] Key type lgssc unregistered [ 901.711505] LNet: 25237:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 901.717313] LNetError: 25237:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 901.732136] LNet: Removed LNI 192.168.202.29@tcp [ 902.119338] Key type .llcrypt unregistered [ 902.120912] Key type ._llcrypt unregistered [ 910.062173] Key type ._llcrypt registered [ 910.063727] Key type .llcrypt registered [ 910.749615] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 23:01:26 (1763352086) [ 911.928676] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 911.947203] alg: No test for adler32 (adler32-zlib) [ 912.908483] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 913.040677] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 914.672427] Key type lgssc registered [ 915.341278] Lustre: Echo OBD driver; http://www.lustre.org/ [ 943.771603] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 946.116643] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 957.148170] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 958.478346] Lustre: Mounted lustre-client [ 968.679314] Lustre: lustre-MDT0000-mdc-ffff8cced0e26800: Connection to lustre-MDT0000 (at 192.168.202.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 973.801813] Lustre: lustre-MDT0001-mdc-ffff8cced0e26800: Connection to lustre-MDT0001 (at 192.168.202.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 980.712179] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 989.152195] Lustre: 26194:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763352150/real 1763352150] req@000000001f3c369b x1849008681129984/t0(0) o400->MGC192.168.202.129@tcp@192.168.202.129@tcp:26/25 lens 224/224 e 0 to 1 dl 1763352166 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 989.166852] LustreError: MGC192.168.202.129@tcp: Connection to MGS (at 192.168.202.129@tcp) was lost; in progress operations using this service will fail [ 990.955281] LustreError: 28408:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 990.983253] Lustre: Unmounted lustre-client [ 1005.057363] Key type lgssc unregistered [ 1005.220719] LNet: 28798:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1005.225580] LNetError: 28798:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1005.242058] LNet: Removed LNI 192.168.202.29@tcp [ 1005.595970] Key type .llcrypt unregistered [ 1005.598393] Key type ._llcrypt unregistered [ 1013.105272] Key type ._llcrypt registered [ 1013.106790] Key type .llcrypt registered [ 1013.910186] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 23:03:10 (1763352190) [ 1015.347935] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1015.489508] alg: No test for adler32 (adler32-zlib) [ 1016.427488] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 1016.577601] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 1018.200206] Key type lgssc registered [ 1018.909383] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1047.189959] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1049.095334] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1059.668440] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1062.020549] Lustre: Mounted lustre-client [ 1077.739057] Lustre: lustre-OST0000-osc-ffff8ccec4c54800: Connection to lustre-OST0000 (at 192.168.202.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1081.116912] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1091.376955] LustreError: 31912:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1091.426335] Lustre: Unmounted lustre-client [ 1119.130028] Key type lgssc unregistered [ 1119.266102] LNet: 32313:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1119.270627] LNetError: 32313:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1119.281929] LNet: Removed LNI 192.168.202.29@tcp [ 1119.619646] Key type .llcrypt unregistered [ 1119.623656] Key type ._llcrypt unregistered [ 1126.235650] Key type ._llcrypt registered [ 1126.237130] Key type .llcrypt registered [ 1126.827746] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 23:05:03 (1763352303) [ 1127.899283] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1127.915164] alg: No test for adler32 (adler32-zlib) [ 1128.809902] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 1128.913112] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 1130.512205] Key type lgssc registered [ 1131.013307] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1155.412127] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1157.257993] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1166.147159] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1172.990564] Lustre: Mounted lustre-client [ 1178.819798] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1180.618148] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1191.408042] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2025-11-1623h06m07s [ 1192.456367] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1198.567889] Lustre: lustre-MDT0000-mdc-ffff8cced121a800: Connection to lustre-MDT0000 (at 192.168.202.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1200.567508] LustreError: 36006:0:(lmv_obd.c:1435:lmv_statfs()) lustre-MDT0000-mdc-ffff8cced121a800: can't stat MDS #0: rc = -19 [ 1201.208151] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1204.644777] LustreError: MGC192.168.202.129@tcp: Connection to MGS (at 192.168.202.129@tcp) was lost; in progress operations using this service will fail [ 1204.650949] LustreError: lustre-MDT0000-mdc-ffff8cced121a800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1204.659904] Lustre: Evicted from MGS (at 192.168.202.129@tcp) after server handle changed from 0x611fe3bf73c75b22 to 0x611fe3bf73c75ec5 [ 1204.662232] Lustre: lustre-MDT0000-mdc-ffff8cced121a800: Connection restored to 192.168.202.129@tcp (at 192.168.202.129@tcp) [ 1204.669879] Lustre: Skipped 1 previous similar message [ 1207.893418] Lustre: DEBUG MARKER: Wait for df (36006) ... [ 1208.586846] Lustre: DEBUG MARKER: done [ 1209.066263] LustreError: 36617:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cced121a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1209.071271] LustreError: 36617:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1209.101994] Lustre: Unmounted lustre-client [ 1227.817529] Key type lgssc unregistered [ 1227.951841] LNet: 37019:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1227.955702] LNetError: 37019:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1227.966104] LNet: Removed LNI 192.168.202.29@tcp [ 1228.245722] Key type .llcrypt unregistered [ 1228.246927] Key type ._llcrypt unregistered [ 1248.681609] Key type ._llcrypt registered [ 1248.683342] Key type .llcrypt registered [ 1248.895075] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1248.901156] alg: No test for adler32 (adler32-zlib) [ 1249.792824] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 1249.921401] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 1251.528166] Key type lgssc registered [ 1252.110973] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1289.090871] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1290.573623] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1299.590562] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1323.944280] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 23:08:20 (1763352500) [ 1337.527729] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1339.354076] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1348.612924] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1354.236877] Lustre: Mounted lustre-client [ 1356.648617] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2025-11-1623h08m53s [ 1357.283098] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1360.719557] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1361.919744] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1382.368679] Lustre: 43871:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763352538/real 1763352538] req@0000000093408200 x1849009033451392/t0(0) o101->lustre-MDT0000-mdc-ffff8ccec4c53800@192.168.202.129@tcp:12/10 lens 576/1152 e 0 to 1 dl 1763352558 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:0 [ 1382.390118] Lustre: lustre-MDT0000-mdc-ffff8ccec4c53800: Connection to lustre-MDT0000 (at 192.168.202.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1382.409420] Lustre: lustre-MDT0000-mdc-ffff8ccec4c53800: Connection restored to 192.168.202.129@tcp (at 192.168.202.129@tcp) [ 1402.722101] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1404.062875] Lustre: DEBUG MARKER: done [ 1405.286780] LustreError: 44351:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ccec4c53800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1405.295426] LustreError: 44351:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1405.316181] Lustre: Unmounted lustre-client [ 1424.279609] Key type lgssc unregistered [ 1424.418739] LNet: 44752:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1424.425582] LNetError: 44752:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1424.437050] LNet: Removed LNI 192.168.202.29@tcp [ 1424.754680] Key type .llcrypt unregistered [ 1424.756486] Key type ._llcrypt unregistered [ 1443.849297] Key type ._llcrypt registered [ 1443.851413] Key type .llcrypt registered [ 1444.036364] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1444.054518] alg: No test for adler32 (adler32-zlib) [ 1444.947497] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 1445.050717] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 1446.672215] Key type lgssc registered [ 1447.200402] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1486.689817] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1488.540542] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1498.856229] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1520.426871] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 23:11:36 (1763352696) [ 1521.123383] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1521.908546] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 23:11:38 (1763352698) [ 1533.415830] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 23:11:49 (1763352709) [ 1547.119250] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1548.851542] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1557.800442] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1564.160051] Lustre: Mounted lustre-client [ 1569.942899] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 1571.447025] LustreError: 52572:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ccec79be800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1571.460547] LustreError: 52572:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1571.498324] Lustre: Unmounted lustre-client [ 1582.589527] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 1602.057884] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1603.671211] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1603.904404] Lustre: Mounted lustre-client [ 1605.119193] LustreError: 53828:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ccec4c50800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1605.134125] LustreError: 53828:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1605.136599] LustreError: 53828:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 1605.165263] Lustre: Unmounted lustre-client [ 1635.413743] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1636.872484] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1637.119925] Lustre: Mounted lustre-client [ 1638.392670] LustreError: 54944:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cced1219000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1638.405719] LustreError: 54944:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1638.408480] LustreError: 54944:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 1638.442089] Lustre: Unmounted lustre-client [ 1651.723849] Lustre: DEBUG MARKER: files compared the same [ 1667.290245] Key type lgssc unregistered [ 1667.422349] LNet: 55648:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1667.426552] LNetError: 55648:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1667.437418] LNet: Removed LNI 192.168.202.29@tcp [ 1667.705439] Key type .llcrypt unregistered [ 1667.706742] Key type ._llcrypt unregistered [ 1674.190453] Key type ._llcrypt registered [ 1674.192124] Key type .llcrypt registered [ 1674.793867] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 23:14:11 (1763352851) [ 1675.743771] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1675.764273] alg: No test for adler32 (adler32-zlib) [ 1676.647429] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 1676.747614] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 1678.344183] Key type lgssc registered [ 1678.801454] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1700.029159] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1701.565368] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1709.252878] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1719.797639] Lustre: Mounted lustre-client [ 1721.574468] LustreError: 58733:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ccec3279800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1721.584491] LustreError: 58733:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1721.611647] Lustre: Unmounted lustre-client [ 1748.625238] Key type lgssc unregistered [ 1748.752630] LNet: 59136:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1748.759411] LNetError: 59136:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1748.775389] LNet: Removed LNI 192.168.202.29@tcp [ 1749.086428] Key type .llcrypt unregistered [ 1749.087868] Key type ._llcrypt unregistered [ 1755.535954] Key type ._llcrypt registered [ 1755.537242] Key type .llcrypt registered [ 1756.132180] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 23:15:32 (1763352932) [ 1757.072841] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1757.084390] alg: No test for adler32 (adler32-zlib) [ 1757.985662] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 1758.087708] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 1759.688222] Key type lgssc registered [ 1760.234195] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1823.810713] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1837.485886] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1838.975562] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1857.641610] Key type lgssc unregistered [ 1857.784359] LNet: 62533:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1857.787849] LNetError: 62533:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1857.797300] LNet: Removed LNI 192.168.202.29@tcp [ 1858.065783] Key type .llcrypt unregistered [ 1858.067567] Key type ._llcrypt unregistered [ 1864.467790] Key type ._llcrypt registered [ 1864.469156] Key type .llcrypt registered [ 1865.063942] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 23:17:21 (1763353041) [ 1866.505614] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1866.556492] alg: No test for adler32 (adler32-zlib) [ 1867.461097] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 1867.587674] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 1869.192361] Key type lgssc registered [ 1869.716772] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1902.660625] Lustre: Mounted lustre-client [ 1908.098675] LustreError: 65015:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cced0511000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1908.108117] LustreError: 65015:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1908.133070] Lustre: Unmounted lustre-client [ 1930.983036] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 23:18:27 (1763353107) [ 1981.767637] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1994.231237] Lustre: Mounted lustre-client [ 1999.690204] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1999.693425] LustreError: 68969:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff8ccec4c51000: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 1999.702954] Lustre: 63512:0:(llite_lib.c:4226:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.202.129@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 2009.961384] LustreError: 68969:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2009.967191] LustreError: 68969:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 2009.986676] Lustre: Unmounted lustre-client [ 2046.944761] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 23:20:23 (1763353223) [ 2051.584783] Key type lgssc unregistered [ 2051.719784] LNet: 70177:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2051.723192] LNetError: 70177:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2051.732834] LNet: Removed LNI 192.168.202.29@tcp [ 2051.991182] Key type .llcrypt unregistered [ 2051.992346] Key type ._llcrypt unregistered [ 2056.630338] Key type ._llcrypt registered [ 2056.632306] Key type .llcrypt registered [ 2056.941378] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2056.966760] alg: No test for adler32 (adler32-zlib) [ 2057.856487] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 2057.958976] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 2059.552304] Key type lgssc registered [ 2059.998351] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2080.885355] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2082.322290] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2089.709968] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2100.216808] Lustre: Mounted lustre-client [ 2102.049791] LustreError: 72691:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cced0514000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2102.064358] LustreError: 72691:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2102.092216] Lustre: Unmounted lustre-client [ 2120.792364] Key type lgssc unregistered [ 2120.931669] LNet: 73094:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2120.934750] LNetError: 73094:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2120.945644] LNet: Removed LNI 192.168.202.29@tcp [ 2121.200587] Key type .llcrypt unregistered [ 2121.202157] Key type ._llcrypt unregistered [ 2125.097364] Key type ._llcrypt registered [ 2125.099994] Key type .llcrypt registered [ 2125.316771] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2125.328838] alg: No test for adler32 (adler32-zlib) [ 2126.213470] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 2126.304219] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 2127.896256] Key type lgssc registered [ 2128.332542] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2181.797483] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2183.237698] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2190.515295] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2201.074895] Lustre: Mounted lustre-client [ 2201.589849] LustreError: 76024:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ccec4a7c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2201.598770] LustreError: 76024:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2201.625126] Lustre: Unmounted lustre-client [ 2219.647689] Key type lgssc unregistered [ 2219.780613] LNet: 76425:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2219.784041] LNetError: 76425:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2219.793811] LNet: Removed LNI 192.168.202.29@tcp [ 2220.041227] Key type .llcrypt unregistered [ 2220.043063] Key type ._llcrypt unregistered [ 2225.767090] Key type ._llcrypt registered [ 2225.768849] Key type .llcrypt registered [ 2226.388905] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 23:23:22 (1763353402) [ 2227.376491] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2227.384372] alg: No test for adler32 (adler32-zlib) [ 2228.272164] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 2228.374629] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 2229.976172] Key type lgssc registered [ 2230.445281] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2250.587608] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2252.192514] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2259.306774] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2269.691092] Lustre: Mounted lustre-client [ 2272.215860] LustreError: 79536:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ccec8142000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2272.226070] LustreError: 79536:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2272.258176] Lustre: Unmounted lustre-client [ 2272.418883] Lustre: Mounted lustre-client [ 2273.168748] LustreError: 79610:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ccec317c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2273.177321] LustreError: 79610:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2273.180099] LustreError: 79610:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 2273.215084] Lustre: Unmounted lustre-client [ 2291.062533] Key type lgssc unregistered [ 2291.192599] LNet: 80012:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2291.196229] LNetError: 80012:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2291.206803] LNet: Removed LNI 192.168.202.29@tcp [ 2291.480819] Key type .llcrypt unregistered [ 2291.482367] Key type ._llcrypt unregistered [ 2296.117287] Key type ._llcrypt registered [ 2296.120166] Key type .llcrypt registered [ 2296.308550] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2296.320088] alg: No test for adler32 (adler32-zlib) [ 2297.218406] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 2297.348756] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 2298.960165] Key type lgssc registered [ 2299.428360] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2319.286575] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2320.734774] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2328.291360] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2338.804960] Lustre: Mounted lustre-client [ 2340.634236] LustreError: 82547:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ccec8147000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2340.642825] LustreError: 82547:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2340.667761] Lustre: Unmounted lustre-client [ 2367.487734] Key type lgssc unregistered [ 2367.611771] LNet: 82950:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2367.614679] LNetError: 82950:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2367.625408] LNet: Removed LNI 192.168.202.29@tcp [ 2367.893420] Key type .llcrypt unregistered [ 2367.895095] Key type ._llcrypt unregistered [ 2373.522625] Key type ._llcrypt registered [ 2373.524211] Key type .llcrypt registered [ 2374.041378] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 23:25:50 (1763353550) [ 2374.894797] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2374.930622] alg: No test for adler32 (adler32-zlib) [ 2375.799544] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 2375.892597] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 2377.480188] Key type lgssc registered [ 2377.964620] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2397.452520] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2398.881694] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2405.877709] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2416.632040] Lustre: Mounted lustre-client [ 2421.632832] Lustre: lustre: root_squash is set to 0:0 [ 2421.647530] Lustre: lustre: nosquash_nids is cleared [ 2428.917299] Lustre: lustre: root_squash is set to 500:500 [ 2439.149964] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.202.29@tcp 192.168.0.[2,10]@tcp [ 2448.366910] Lustre: lustre: nosquash_nids set to 192.168.202.29/24@tcp [ 2449.727162] LustreError: 86459:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ccec9234000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2449.734447] LustreError: 86459:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2449.751695] Lustre: Unmounted lustre-client [ 2467.088280] Key type lgssc unregistered [ 2467.213507] LNet: 86862:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2467.215765] LNetError: 86862:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2467.224649] LNet: Removed LNI 192.168.202.29@tcp [ 2467.457742] Key type .llcrypt unregistered [ 2467.459243] Key type ._llcrypt unregistered [ 2472.568526] Key type ._llcrypt registered [ 2472.570202] Key type .llcrypt registered [ 2473.060595] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 23:27:29 (1763353649) [ 2473.881438] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 2474.436711] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 23:27:30 (1763353650) [ 2475.230436] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2475.245208] alg: No test for adler32 (adler32-zlib) [ 2476.100604] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 2476.190425] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 2477.776152] Key type lgssc registered [ 2478.164176] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2495.757074] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2496.976961] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2503.179376] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2503.329030] Lustre: lustre: root_squash is set to 500:500 [ 2503.336778] Lustre: lustre: nosquash_nids set to 192.168.202.29/24@tcp [ 2513.906156] Lustre: Mounted lustre-client [ 2516.245397] LustreError: 90318:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cced121b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2516.253886] LustreError: 90318:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2516.277136] Lustre: Unmounted lustre-client [ 2533.679777] Key type lgssc unregistered [ 2533.794516] LNet: 90720:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2533.797311] LNetError: 90720:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2533.805580] LNet: Removed LNI 192.168.202.29@tcp [ 2534.043824] Key type .llcrypt unregistered [ 2534.045227] Key type ._llcrypt unregistered [ 2539.077986] Key type ._llcrypt registered [ 2539.078993] Key type .llcrypt registered [ 2539.530537] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 2540.093761] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 23:28:36 (1763353716) [ 2546.990125] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2547.000285] alg: No test for adler32 (adler32-zlib) [ 2547.881537] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 2547.976770] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 2549.568206] Key type lgssc registered [ 2550.061884] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2580.210627] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2581.401355] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2589.217767] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2622.809857] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2624.180969] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2630.805632] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2641.915427] Lustre: Mounted lustre-client [ 2656.353083] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8cced0517000.ost_server_uuid 50 [ 2656.889751] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8cced0517000.ost_server_uuid in FULL state after 0 sec [ 2657.036131] Lustre: Mounted lustre-client [ 2657.807328] LustreError: 97602:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ccec9aac000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2657.816458] LustreError: 97602:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 2657.851268] Lustre: Unmounted lustre-client [ 2693.819664] Key type lgssc unregistered [ 2693.941811] LNet: 98175:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2693.944185] LNetError: 98175:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2693.954461] LNet: Removed LNI 192.168.202.29@tcp [ 2694.199461] Key type .llcrypt unregistered [ 2694.200640] Key type ._llcrypt unregistered [ 2707.905525] Key type ._llcrypt registered [ 2707.906630] Key type .llcrypt registered [ 2708.102343] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2708.115074] alg: No test for adler32 (adler32-zlib) [ 2708.969565] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 2709.052663] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 2710.632166] Key type lgssc registered [ 2711.022206] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2739.429535] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2740.640524] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2748.053902] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2769.326104] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 23:32:25 (1763353945) [ 2799.055427] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2800.238315] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2807.667922] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2808.892089] Lustre: Mounted lustre-client [ 2824.164129] Lustre: lustre-OST0000-osc-ffff8ccec7738000: Connection to lustre-OST0000 (at 192.168.202.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2829.216178] Lustre: 98957:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763353990/real 1763353990] req@00000000da63841b x1849010564371584/t0(0) o400->lustre-OST0000-osc-ffff8ccec7738000@192.168.202.129@tcp:28/4 lens 224/224 e 0 to 1 dl 1763354006 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2829.282382] Lustre: lustre-MDT0000-mdc-ffff8ccec7738000: Connection to lustre-MDT0000 (at 192.168.202.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2835.424173] Lustre: 98957:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763353996/real 1763353996] req@00000000bf437311 x1849010564372096/t0(0) o400->lustre-OST0000-osc-ffff8ccec7738000@192.168.202.129@tcp:28/4 lens 224/224 e 0 to 1 dl 1763354012 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2839.522512] LustreError: MGC192.168.202.129@tcp: Connection to MGS (at 192.168.202.129@tcp) was lost; in progress operations using this service will fail [ 2839.527772] Lustre: Evicted from MGS (at 192.168.202.129@tcp) after server handle changed from 0x59a6bb008277389e to 0x59a6bb0082773b3e [ 2839.531328] Lustre: MGC192.168.202.129@tcp: Connection restored to 192.168.202.129@tcp (at 192.168.202.129@tcp) [ 2844.411608] Lustre: lustre-MDT0000-mdc-ffff8ccec7738000: Connection restored to 192.168.202.129@tcp (at 192.168.202.129@tcp) [ 2844.761937] LustreError: 106808:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ccec7738000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2844.768723] LustreError: 106808:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2844.786124] Lustre: Unmounted lustre-client [ 2861.671819] Key type lgssc unregistered [ 2861.783617] LNet: 107209:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2861.787243] LNetError: 107209:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2861.793729] LNet: Removed LNI 192.168.202.29@tcp [ 2862.036204] Key type .llcrypt unregistered [ 2862.037702] Key type ._llcrypt unregistered [ 2866.689648] Key type ._llcrypt registered [ 2866.690460] Key type .llcrypt registered [ 2867.097489] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 23:34:03 (1763354043) [ 2878.601563] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2878.611384] alg: No test for adler32 (adler32-zlib) [ 2879.463520] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 2879.552237] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 2881.136167] Key type lgssc registered [ 2881.529255] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2895.671829] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2928.342241] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2929.406949] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2936.522346] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2937.674228] Lustre: Mounted lustre-client [ 2938.318782] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 2938.857783] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 23:35:15 (1763354115) [ 2992.886027] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3003.174139] LustreError: 117939:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3003.187050] Lustre: Unmounted lustre-client [ 3060.313936] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3061.425648] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3068.529713] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3089.643405] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 23:37:46 (1763354266) [ 3118.718371] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3120.039424] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3127.373568] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3132.913211] Lustre: Mounted lustre-client [ 3133.978853] LustreError: 125329:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ccec7855000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3133.987630] LustreError: 125329:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3133.990507] LustreError: 125329:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 3134.018183] Lustre: Unmounted lustre-client [ 3160.874816] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 23:38:57 (1763354337) [ 3191.117517] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3192.308349] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3199.925398] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3205.106192] Lustre: Mounted lustre-client [ 3206.213713] LustreError: 129367:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ccec773f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3206.220332] LustreError: 129367:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3206.222320] LustreError: 129367:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 3206.246216] Lustre: Unmounted lustre-client [ 3223.527192] Key type lgssc unregistered [ 3223.646504] LNet: 129770:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3223.649280] LNetError: 129770:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3223.661866] LNet: Removed LNI 192.168.202.29@tcp [ 3223.925489] Key type .llcrypt unregistered [ 3223.926496] Key type ._llcrypt unregistered [ 3228.977482] Key type ._llcrypt registered [ 3228.978815] Key type .llcrypt registered [ 3229.420337] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 23:40:05 (1763354405) [ 3230.136689] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3230.196098] alg: No test for adler32 (adler32-zlib) [ 3231.064362] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 3231.151190] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 3232.736193] Key type lgssc registered [ 3233.136109] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3250.144445] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3251.367378] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3257.438889] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3268.081605] Lustre: Mounted lustre-client [ 3278.753422] LustreError: 132861:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ccec2b80000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3278.760279] LustreError: 132861:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3278.780115] Lustre: Unmounted lustre-client [ 3296.089581] Key type lgssc unregistered [ 3296.207535] LNet: 133262:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3296.210122] LNetError: 133262:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3296.217874] LNet: Removed LNI 192.168.202.29@tcp [ 3296.461985] Key type .llcrypt unregistered [ 3296.462991] Key type ._llcrypt unregistered [ 3301.622727] Key type ._llcrypt registered [ 3301.624061] Key type .llcrypt registered [ 3302.080166] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 23:41:18 (1763354478) [ 3303.016852] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3303.027525] alg: No test for adler32 (adler32-zlib) [ 3303.896718] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 3303.984707] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 3305.560114] Key type lgssc registered [ 3305.950250] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3323.027996] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3324.219325] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3330.337803] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3340.782521] Lustre: Mounted lustre-client [ 3345.893055] Lustre: lustre-OST0000-osc-ffff8ccec773c800: Connection to lustre-OST0000 (at 192.168.202.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3350.928649] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff8ccec773c800.ost_server_uuid 50 [ 3351.421544] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8ccec773c800.ost_server_uuid in DISCONN state after 0 sec [ 3351.992096] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 3361.309065] LustreError: 136926:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ccec773c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3361.315144] LustreError: 136926:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3361.334160] Lustre: Unmounted lustre-client [ 3372.418214] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 23:42:28 (1763354548) [ 3382.526575] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3383.702102] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3389.731216] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3397.094341] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3402.742977] Lustre: Mounted lustre-client [ 3407.845359] Lustre: lustre-OST0000-osc-ffff8ccec78e9000: Connection to lustre-OST0000 (at 192.168.202.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3422.162786] LustreError: 139573:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ccec78e9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3422.168872] LustreError: 139573:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 3422.170887] LustreError: 139573:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 3422.190576] Lustre: Unmounted lustre-client [ 3453.921143] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 23:43:50 (1763354630) [ 3463.669674] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3464.940995] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3470.988901] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3476.912666] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3477.076713] Lustre: Mounted lustre-client [ 3482.084858] Lustre: lustre-OST0000-osc-ffff8ccec71b5000: Connection to lustre-OST0000 (at 192.168.202.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3493.787051] LustreError: 142323:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ccec71b5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3493.791383] LustreError: 142323:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 3493.796692] LustreError: 142323:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 3493.798645] LustreError: 142323:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 3493.820146] Lustre: Unmounted lustre-client [ 3517.105075] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 23:44:53 (1763354693) [ 3549.851680] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3551.019420] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3558.195157] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3586.656618] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3587.907806] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3593.889360] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3616.950537] Lustre: Mounted lustre-client [ 3644.120403] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3644.680118] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 3644.983658] LustreError: 148905:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cced02d5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3644.986488] LustreError: 148905:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 3644.990432] LustreError: 148905:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3644.992410] LustreError: 148905:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 3645.012087] Lustre: Unmounted lustre-client [ 3655.148553] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 23:47:11 (1763354831) [ 3664.585936] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3665.712134] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3671.609987] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3691.619285] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3707.704796] Lustre: Mounted lustre-client [ 3735.013568] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3735.553195] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 3738.595695] Lustre: lustre-OST0001-osc-ffff8cced0510800: Connection to lustre-OST0001 (at 192.168.202.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3743.175826] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3753.448072] LustreError: 152473:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 3753.450194] LustreError: 152473:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 3753.466100] Lustre: Unmounted lustre-client [ 3799.409478] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 23:49:35 (1763354975) [ 3808.970178] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3810.230802] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3816.287271] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3821.554451] Lustre: Mounted lustre-client [ 3828.850732] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3830.403415] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 1 sec [ 3834.215785] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8cced02d4000.ost_server_uuid 50 [ 3834.691956] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8cced02d4000.ost_server_uuid in FULL state after 0 sec [ 3835.067348] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 3835.069126] Lustre: Skipped 3 previous similar messages [ 3835.297482] LustreError: 155897:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cced02d4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3835.302857] LustreError: 155897:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 3835.304388] LustreError: 155897:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 3835.324448] Lustre: Unmounted lustre-client [ 3835.795421] LustreError: 155962:0:(sec.c:410:import_sec_validate_get()) import 0000000072898a64 (NEW) with no sec [ 3873.192689] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 23:50:49 (1763355049) [ 3882.833817] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3884.015317] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3889.960774] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3895.798871] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3895.947209] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 3895.950052] Lustre: Skipped 1 previous similar message [ 3901.427148] Lustre: Mounted lustre-client [ 3901.428262] Lustre: Skipped 1 previous similar message [ 3907.985539] LustreError: 158796:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cced121d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3907.991083] LustreError: 158796:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 3908.004070] LustreError: 158796:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 3908.005980] LustreError: 158796:0:(obd_class.h:479:obd_check_dev()) Skipped 18 previous similar messages [ 3908.028264] Lustre: Unmounted lustre-client [ 3908.029838] Lustre: Skipped 1 previous similar message [ 3932.440472] Key type lgssc unregistered [ 3932.550422] LNet: 159243:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3932.553091] LNetError: 159243:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3932.561947] LNet: Removed LNI 192.168.202.29@tcp [ 3932.799239] Key type .llcrypt unregistered [ 3932.800375] Key type ._llcrypt unregistered [ 3937.767593] Key type ._llcrypt registered [ 3937.768447] Key type .llcrypt registered [ 3938.229858] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 23:51:54 (1763355114) [ 3938.636639] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3938.646057] alg: No test for adler32 (adler32-zlib) [ 3939.504073] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 3939.585310] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 3941.168142] Key type lgssc registered [ 3941.538879] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3958.984644] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3960.135938] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3966.122550] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3972.034569] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3972.154277] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 3977.199806] Lustre: Mounted lustre-client [ 3978.412378] LustreError: lustre-MDT0001-mdc-ffff8cced02d3000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 3978.418127] Lustre: lustre-MDT0001-mdc-ffff8cced02d3000: Connection restored to 192.168.202.129@tcp (at 192.168.202.129@tcp) [ 3979.827442] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3980.359642] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 3988.135179] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 3994.229174] LustreError: 163416:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cced02d3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3994.235290] LustreError: 163416:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 3994.256118] Lustre: Unmounted lustre-client [ 4013.401315] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 23:53:09 (1763355189) [ 4043.940080] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4045.077260] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4052.287228] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4052.468794] Lustre: Mounted lustre-client [ 4062.179253] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4062.752839] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 4078.053444] Lustre: lustre-OST0001-osc-ffff8ccec5ad9000: Connection to lustre-OST0001 (at 192.168.202.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4081.017279] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4091.305465] LustreError: 168139:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 4091.308027] LustreError: 168139:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 4091.326174] Lustre: Unmounted lustre-client [ 4123.148935] Key type lgssc unregistered [ 4123.273388] LNet: 168544:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4123.275591] LNetError: 168544:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4123.284426] LNet: Removed LNI 192.168.202.29@tcp [ 4123.521318] Key type .llcrypt unregistered [ 4123.523026] Key type ._llcrypt unregistered [ 4138.276996] Key type ._llcrypt registered [ 4138.281232] Key type .llcrypt registered [ 4138.529843] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4138.535887] alg: No test for adler32 (adler32-zlib) [ 4139.402488] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 4139.497107] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4141.088216] Key type lgssc registered [ 4141.543321] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4170.913947] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4172.196315] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4179.420697] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4200.576888] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 23:56:17 (1763355377) [ 4210.062948] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4211.198955] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4217.016188] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4232.688836] Lustre: Mounted lustre-client [ 4269.863856] LustreError: 174609:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cced0516800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4269.872802] LustreError: 174609:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4269.893937] Lustre: Unmounted lustre-client [ 4286.061436] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4286.214410] Lustre: Mounted lustre-client [ 4318.583478] LustreError: 175266:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ccec5ad8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4318.591276] LustreError: 175266:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4318.592936] LustreError: 175266:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4318.613120] Lustre: Unmounted lustre-client [ 4336.051348] Key type lgssc unregistered [ 4336.179687] LNet: 175673:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4336.182146] LNetError: 175673:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4336.191778] LNet: Removed LNI 192.168.202.29@tcp [ 4336.480244] Key type .llcrypt unregistered [ 4336.482035] Key type ._llcrypt unregistered [ 4341.733569] Key type ._llcrypt registered [ 4341.735164] Key type .llcrypt registered [ 4342.243733] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 23:58:38 (1763355518) [ 4343.023565] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4343.035715] alg: No test for adler32 (adler32-zlib) [ 4343.941649] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 4344.029621] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4345.624213] Key type lgssc registered [ 4346.072057] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4363.075271] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4364.302066] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4370.191921] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4376.538362] Lustre: Mounted lustre-client [ 4382.981000] LustreError: 178923:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ccec78ed800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4382.988292] LustreError: 178923:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4383.009156] Lustre: Unmounted lustre-client [ 4400.321825] Key type lgssc unregistered [ 4400.436565] LNet: 179326:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4400.440405] LNetError: 179326:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4400.453479] LNet: Removed LNI 192.168.202.29@tcp [ 4400.687900] Key type .llcrypt unregistered [ 4400.689074] Key type ._llcrypt unregistered [ 4404.999970] Key type ._llcrypt registered [ 4405.014289] Key type .llcrypt registered [ 4405.210349] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4405.215590] alg: No test for adler32 (adler32-zlib) [ 4406.076033] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 4406.180398] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4407.776223] Key type lgssc registered [ 4408.166393] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4425.473742] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4426.729792] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4433.106785] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4443.631641] Lustre: Mounted lustre-client [ 4446.383490] LustreError: 181889:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cced0514800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4446.389238] LustreError: 181889:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4446.408489] Lustre: Unmounted lustre-client [ 4463.491269] Key type lgssc unregistered [ 4463.598614] LNet: 182290:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4463.601079] LNetError: 182290:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4463.612860] LNet: Removed LNI 192.168.202.29@tcp [ 4463.834212] Key type .llcrypt unregistered [ 4463.835768] Key type ._llcrypt unregistered [ 4468.851814] Key type ._llcrypt registered [ 4468.853453] Key type .llcrypt registered [ 4469.293438] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 00:00:45 (1763355645) [ 4470.111189] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4470.145971] alg: No test for adler32 (adler32-zlib) [ 4471.008416] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 4471.101377] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4472.696177] Key type lgssc registered [ 4473.067021] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4489.638852] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4490.833773] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4496.800798] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4512.241481] Lustre: Mounted lustre-client [ 4518.949671] LustreError: 185554:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ccec5adf000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4518.959776] LustreError: 185554:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4518.982276] Lustre: Unmounted lustre-client [ 4536.072886] Key type lgssc unregistered [ 4536.186499] LNet: 185957:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4536.190300] LNetError: 185957:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4536.199747] LNet: Removed LNI 192.168.202.29@tcp [ 4536.419498] Key type .llcrypt unregistered [ 4536.421261] Key type ._llcrypt unregistered [ 4540.306195] Key type ._llcrypt registered [ 4540.307974] Key type .llcrypt registered [ 4540.499563] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4540.506244] alg: No test for adler32 (adler32-zlib) [ 4541.363951] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 4541.455202] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4543.032109] Key type lgssc registered [ 4543.419370] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4561.270841] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4562.429658] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4568.425823] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4578.800591] Lustre: Mounted lustre-client [ 4581.684873] LustreError: 188518:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cceca096800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4581.690726] LustreError: 188518:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4581.713174] Lustre: Unmounted lustre-client [ 4590.650829] Key type lgssc unregistered [ 4590.762598] LNet: 188919:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4590.764707] LNetError: 188919:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4590.775889] LNet: Removed LNI 192.168.202.29@tcp [ 4590.999707] Key type .llcrypt unregistered [ 4591.000737] Key type ._llcrypt unregistered [ 4596.104152] Key type ._llcrypt registered [ 4596.105358] Key type .llcrypt registered [ 4596.543352] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 00:02:53 (1763355773) [ 4603.426074] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4603.438232] alg: No test for adler32 (adler32-zlib) [ 4604.298486] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 4604.382767] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4605.968094] Key type lgssc registered [ 4606.336180] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4634.534464] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4635.688473] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4643.187371] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4664.204766] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 00:04:00 (1763355840) [ 4673.611499] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4674.745902] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4680.742560] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4696.565066] Lustre: Mounted lustre-client [ 4698.416978] LustreError: 195415:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ccec7da3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4698.424732] LustreError: 195415:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4698.446195] Lustre: Unmounted lustre-client [ 4715.823429] Key type lgssc unregistered [ 4715.945498] LNet: 195815:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4715.947718] LNetError: 195815:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4715.955519] LNet: Removed LNI 192.168.202.29@tcp [ 4716.170912] Key type .llcrypt unregistered [ 4716.171832] Key type ._llcrypt unregistered [ 4720.967528] Key type ._llcrypt registered [ 4720.968429] Key type .llcrypt registered [ 4721.393525] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 4721.862587] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 00:04:58 (1763355898) [ 4727.766469] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4727.774721] alg: No test for adler32 (adler32-zlib) [ 4728.636053] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 4728.733219] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4730.320163] Key type lgssc registered [ 4730.733661] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4761.377308] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4762.515302] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4769.802263] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid [ 4775.478155] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid [ 4776.641431] Lustre: Mounted lustre-client [ 4782.265139] LustreError: 201333:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cced121e000: lov tgt 1000 not cleaned! deathrow=0, lovrc=1 [ 4782.273494] LustreError: 201333:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4782.293756] Lustre: Unmounted lustre-client [ 4842.374614] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4844.075390] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4851.432261] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4871.894201] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 00:07:28 (1763356048) [ 4872.365512] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 4872.889699] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 00:07:29 (1763356049) [ 4889.549899] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4890.672704] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4903.972161] Key type lgssc unregistered [ 4904.084585] LNet: 207487:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4904.088051] LNetError: 207487:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4904.095907] LNet: Removed LNI 192.168.202.29@tcp [ 4904.331954] Key type .llcrypt unregistered [ 4904.333538] Key type ._llcrypt unregistered [ 4908.922574] Key type ._llcrypt registered [ 4908.923458] Key type .llcrypt registered [ 4909.320302] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 00:08:05 (1763356085) [ 4920.686460] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4920.717476] alg: No test for adler32 (adler32-zlib) [ 4921.577498] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 4921.650075] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4923.224109] Key type lgssc registered [ 4923.565952] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4937.163320] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4938.229992] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4945.051402] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4970.454642] Key type lgssc unregistered [ 4970.562289] LNet: 211109:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4970.564358] LNetError: 211109:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4970.574289] LNet: Removed LNI 192.168.202.29@tcp [ 4970.779307] Key type .llcrypt unregistered [ 4970.780322] Key type ._llcrypt unregistered [ 4975.389088] Key type ._llcrypt registered [ 4975.389973] Key type .llcrypt registered [ 4975.789810] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 00:09:12 (1763356152) [ 4976.575891] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4976.590761] alg: No test for adler32 (adler32-zlib) [ 4977.441535] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 4977.522275] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 4979.104180] Key type lgssc registered [ 4979.437750] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4995.637588] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4996.797193] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5002.773758] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5013.489582] Lustre: Mounted lustre-client [ 5018.596210] Lustre: lustre-MDT0000-mdc-ffff8ccec773a000: Connection to lustre-MDT0000 (at 192.168.202.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5023.715447] Lustre: lustre-MDT0001-mdc-ffff8ccec773a000: Connection to lustre-MDT0001 (at 192.168.202.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5039.072197] Lustre: 212095:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763356200/real 1763356200] req@00000000f9d839b7 x1849012942618880/t0(0) o400->MGC192.168.202.129@tcp@192.168.202.129@tcp:26/25 lens 224/224 e 0 to 1 dl 1763356216 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 5039.074303] LustreError: MGC192.168.202.129@tcp: Connection to MGS (at 192.168.202.129@tcp) was lost; in progress operations using this service will fail [ 5039.083561] Lustre: Evicted from MGS (at 192.168.202.129@tcp) after server handle changed from 0x426b7b2fef0b4e89 to 0x426b7b2fef0b67cd [ 5039.088905] Lustre: MGC192.168.202.129@tcp: Connection restored to 192.168.202.129@tcp (at 192.168.202.129@tcp) [ 5054.737288] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5058.531426] LustreError: lustre-MDT0000-mdc-ffff8ccec773a000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 5058.553217] Lustre: lustre-MDT0000-mdc-ffff8ccec773a000: Connection restored to 192.168.202.129@tcp (at 192.168.202.129@tcp) [ 5059.370838] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 4 sec [ 5060.560279] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5061.080108] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 5061.671023] LustreError: 215536:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ccec773a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5061.674354] LustreError: 215536:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5061.695130] Lustre: Unmounted lustre-client [ 5078.774421] Key type lgssc unregistered [ 5078.881334] LNet: 215938:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5078.883420] LNetError: 215938:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5078.889661] LNet: Removed LNI 192.168.202.29@tcp [ 5079.124608] Key type .llcrypt unregistered [ 5079.126177] Key type ._llcrypt unregistered [ 5083.858871] Key type ._llcrypt registered [ 5083.859850] Key type .llcrypt registered [ 5084.274325] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 00:11:00 (1763356260) [ 5084.990835] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5085.039583] alg: No test for adler32 (adler32-zlib) [ 5085.903794] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 5085.980735] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 5087.560138] Key type lgssc registered [ 5088.050838] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5104.602572] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5105.657232] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5127.376448] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5128.537308] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5138.400215] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5145.177878] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5170.015765] Key type lgssc unregistered [ 5170.122385] LNet: 221029:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5170.124594] LNetError: 221029:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5170.137342] LNet: Removed LNI 192.168.202.29@tcp [ 5170.367544] Key type .llcrypt unregistered [ 5170.368568] Key type ._llcrypt unregistered [ 5185.011530] Key type ._llcrypt registered [ 5185.013140] Key type .llcrypt registered [ 5185.188913] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5185.194266] alg: No test for adler32 (adler32-zlib) [ 5186.038460] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 5186.115691] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 5187.696111] Key type lgssc registered [ 5188.038098] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5215.788411] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5216.898214] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5223.874581] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5245.224745] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 00:13:41 (1763356421) [ 5278.337775] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5279.437497] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5286.248279] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5307.604187] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 00:14:44 (1763356484) [ 5309.822592] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 00:14:46 (1763356486) [ 5319.369166] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5320.411499] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5325.906900] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5327.102146] Lustre: Mounted lustre-client [ 5330.188993] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [ 5330.711779] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 5331.193478] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 5331.694556] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [ 5332.451804] Lustre: lustre-MDT0000-mdc-ffff8ccec3178000: Connection to lustre-MDT0000 (at 192.168.202.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5347.811700] LustreError: MGC192.168.202.129@tcp: Connection to MGS (at 192.168.202.129@tcp) was lost; in progress operations using this service will fail [ 5347.817250] Lustre: Evicted from MGS (at 192.168.202.129@tcp) after server handle changed from 0xb471106a18048e5a to 0xb471106a180490de [ 5347.820355] Lustre: MGC192.168.202.129@tcp: Connection restored to 192.168.202.129@tcp (at 192.168.202.129@tcp) [ 5350.647555] Lustre: lustre-MDT0000-mdc-ffff8ccec3178000: Connection restored to 192.168.202.129@tcp (at 192.168.202.129@tcp) [ 5351.739801] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5352.242658] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 5353.992543] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [ 5358.050933] Lustre: lustre-MDT0000-mdc-ffff8ccec3178000: Connection to lustre-MDT0000 (at 192.168.202.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5368.292157] LustreError: MGC192.168.202.129@tcp: Connection to MGS (at 192.168.202.129@tcp) was lost; in progress operations using this service will fail [ 5368.301605] Lustre: 232174:0:(mgc_request.c:1910:mgc_process_log()) MGC192.168.202.129@tcp: IR log lustre-cliir failed, not fatal: rc = -5 [ 5368.305324] Lustre: Evicted from MGS (at 192.168.202.129@tcp) after server handle changed from 0xb471106a180490de to 0xb471106a180493c4 [ 5368.317094] Lustre: MGC192.168.202.129@tcp: Connection restored to 192.168.202.129@tcp (at 192.168.202.129@tcp) [ 5373.181163] Lustre: lustre-MDT0000-mdc-ffff8ccec3178000: Connection restored to 192.168.202.129@tcp (at 192.168.202.129@tcp) [ 5374.274270] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5374.758449] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 5376.274708] LustreError: 234611:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ccec3178000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5376.281818] LustreError: 234611:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5376.298110] Lustre: Unmounted lustre-client [ 5407.443106] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5408.512315] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5414.170722] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5414.346568] Lustre: Mounted lustre-client [ 5419.783812] LustreError: 236230:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ccec7da3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5419.789629] LustreError: 236230:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5419.792315] LustreError: 236230:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5419.810327] Lustre: Unmounted lustre-client [ 5436.563535] Key type lgssc unregistered [ 5436.674626] LNet: 236631:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5436.678765] LNetError: 236631:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5436.686925] LNet: Removed LNI 192.168.202.29@tcp [ 5436.918430] Key type .llcrypt unregistered [ 5436.919973] Key type ._llcrypt unregistered [ 5441.526846] Key type ._llcrypt registered [ 5441.528389] Key type .llcrypt registered [ 5441.918677] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 00:16:58 (1763356618) [ 5443.085395] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5443.091352] alg: No test for adler32 (adler32-zlib) [ 5443.933934] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 5444.006524] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 5445.576097] Key type lgssc registered [ 5445.904529] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5462.023925] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5463.224643] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5468.717699] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5484.528853] Lustre: Mounted lustre-client [ 5487.411540] LustreError: 239794:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ccec465f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5487.416829] LustreError: 239794:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5487.436122] Lustre: Unmounted lustre-client [ 5516.856780] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5517.914681] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5523.597139] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5529.077458] Lustre: Mounted lustre-client [ 5534.926916] LustreError: 241390:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cced0515800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5534.932253] LustreError: 241390:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5534.933593] LustreError: 241390:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5534.951038] Lustre: Unmounted lustre-client [ 5566.478944] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5567.579128] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5573.086273] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5578.738420] Lustre: Mounted lustre-client [ 5579.034796] LustreError: 243001:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ccec6cb7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5579.040983] LustreError: 243001:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5579.043890] LustreError: 243001:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5579.067155] Lustre: Unmounted lustre-client [ 5595.854257] Key type lgssc unregistered [ 5595.974458] LNet: 243404:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5595.978135] LNetError: 243404:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5595.985712] LNet: Removed LNI 192.168.202.29@tcp [ 5596.215569] Key type .llcrypt unregistered [ 5596.216558] Key type ._llcrypt unregistered [ 5600.952588] Key type ._llcrypt registered [ 5600.954120] Key type .llcrypt registered [ 5601.422484] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 00:19:37 (1763356777) [ 5602.518805] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5602.528263] alg: No test for adler32 (adler32-zlib) [ 5603.385212] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 5603.468379] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 5605.056120] Key type lgssc registered [ 5605.454042] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5619.559486] Key type lgssc unregistered [ 5619.667311] LNet: 245713:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5619.669258] LNetError: 245713:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5619.676309] LNet: Removed LNI 192.168.202.29@tcp [ 5619.876438] Key type .llcrypt unregistered [ 5619.877358] Key type ._llcrypt unregistered [ 5628.626614] Key type ._llcrypt registered [ 5628.629047] Key type .llcrypt registered [ 5628.844517] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5628.849071] alg: No test for adler32 (adler32-zlib) [ 5629.706433] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 5629.791569] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 5631.376123] Key type lgssc registered [ 5631.747512] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5658.280873] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5659.274537] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5665.971118] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5686.310347] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [ 5686.821073] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 00:21:03 (1763356863) [ 5695.492381] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5696.551948] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5701.936273] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5708.659408] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5709.813636] Lustre: Mounted lustre-client [ 5714.915975] Lustre: lustre-OST0001-osc-ffff8cced0be0800: Connection to lustre-OST0001 (at 192.168.202.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5717.321435] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5727.592645] LustreError: 252186:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 5727.608424] Lustre: Unmounted lustre-client [ 5750.428782] Key type lgssc unregistered [ 5750.529551] LNet: 252589:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5750.531519] LNetError: 252589:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5750.541931] LNet: Removed LNI 192.168.202.29@tcp [ 5750.735347] Key type .llcrypt unregistered [ 5750.736653] Key type ._llcrypt unregistered [ 5763.259155] Key type ._llcrypt registered [ 5763.260136] Key type .llcrypt registered [ 5763.475619] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5763.481743] alg: No test for adler32 (adler32-zlib) [ 5764.326777] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 5764.406756] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 5765.984103] Key type lgssc registered [ 5766.341989] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5791.980747] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5793.058367] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5799.583403] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5820.568142] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 00:23:17 (1763356997) [ 5832.061127] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5833.122513] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5843.946324] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 00:23:40 (1763357020) [ 5852.539125] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5853.596380] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5858.762538] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5859.917473] Lustre: Mounted lustre-client [ 5864.103870] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 5870.685552] LustreError: 260409:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5870.703344] Lustre: Unmounted lustre-client [ 5914.579301] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5915.507989] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5948.371195] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5958.638054] Lustre: Mounted lustre-client [ 5958.949644] LustreError: 262501:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ccec5ce8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5958.954509] LustreError: 262501:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5958.955927] LustreError: 262501:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5958.973088] Lustre: Unmounted lustre-client [ 5975.278585] Key type lgssc unregistered [ 5975.385724] LNet: 262902:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5975.387430] LNetError: 262902:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5975.394541] LNet: Removed LNI 192.168.202.29@tcp [ 5975.590368] Key type .llcrypt unregistered [ 5975.591674] Key type ._llcrypt unregistered [ 5982.692798] Key type ._llcrypt registered [ 5982.696563] Key type .llcrypt registered [ 5982.828167] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5982.831840] alg: No test for adler32 (adler32-zlib) [ 5983.669240] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 5983.739380] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 5985.312077] Key type lgssc registered [ 5985.593068] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6010.407724] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6011.416419] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6017.864795] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6046.827171] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [ 6047.297991] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 00:27:03 (1763357223) [ 6056.335895] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6057.343264] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6062.447463] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6064.339284] Lustre: Mounted lustre-client [ 6064.899259] LustreError: 268941:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cced02d7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6064.903867] LustreError: 268941:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6064.921030] Lustre: Unmounted lustre-client [ 6081.301955] Key type lgssc unregistered [ 6081.413605] LNet: 269344:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6081.416957] LNetError: 269344:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6081.425831] LNet: Removed LNI 192.168.202.29@tcp [ 6081.618354] Key type .llcrypt unregistered [ 6081.619244] Key type ._llcrypt unregistered [ 6085.874761] Key type ._llcrypt registered [ 6085.875665] Key type .llcrypt registered [ 6086.273493] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [ 6086.727788] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 00:27:43 (1763357263) [ 6089.304188] Key type .llcrypt unregistered [ 6089.305254] Key type ._llcrypt unregistered [ 6091.583367] Key type ._llcrypt registered [ 6091.587680] Key type .llcrypt registered [ 6091.769395] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6091.781753] alg: No test for adler32 (adler32-zlib) [ 6092.619856] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 6092.690645] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 6094.264113] Key type lgssc registered [ 6094.575265] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6110.316200] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6115.138936] Lustre: Mounted lustre-client [ 6115.513768] LustreError: 272348:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ccec6cb3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6115.519172] LustreError: 272348:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6115.536663] Lustre: Unmounted lustre-client [ 6133.164340] Key type lgssc unregistered [ 6133.320757] LNet: 272749:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6133.325348] LNetError: 272749:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6133.335517] LNet: Removed LNI 192.168.202.29@tcp [ 6133.686401] Key type .llcrypt unregistered [ 6133.688459] Key type ._llcrypt unregistered [ 6138.532035] Key type ._llcrypt registered [ 6138.532910] Key type .llcrypt registered [ 6139.114159] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 00:28:35 (1763357315) [ 6140.118432] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6140.143542] alg: No test for adler32 (adler32-zlib) [ 6140.997184] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 6141.068807] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 6142.648160] Key type lgssc registered [ 6143.166049] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6204.304201] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6218.034994] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6219.707478] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6220.975528] Lustre: Mounted lustre-client [ 6221.563745] LustreError: 275789:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ccec5ada800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6221.570754] LustreError: 275789:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6221.597193] Lustre: Unmounted lustre-client [ 6248.919740] Key type lgssc unregistered [ 6249.071735] LNet: 276194:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6249.076364] LNetError: 276194:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6249.090864] LNet: Removed LNI 192.168.202.29@tcp [ 6249.441131] Key type .llcrypt unregistered [ 6249.443131] Key type ._llcrypt unregistered [ 6255.791991] Key type ._llcrypt registered [ 6255.793099] Key type .llcrypt registered [ 6256.359978] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 00:30:32 (1763357432) [ 6257.320272] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6257.333386] alg: No test for adler32 (adler32-zlib) [ 6258.191275] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 6258.263278] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 6259.840106] Key type lgssc registered [ 6260.150281] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6283.306917] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6285.039226] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6293.839500] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6300.131868] Lustre: Mounted lustre-client [ 6305.253848] Lustre: lustre-MDT0000-mdc-ffff8ccec5c89000: Connection to lustre-MDT0000 (at 192.168.202.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6308.243641] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6308.255165] LustreError: 279252:0:(file.c:6099:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [ 6308.399174] LustreError: 279271:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ccec5c89000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6308.405405] LustreError: MGC192.168.202.129@tcp: Connection to MGS (at 192.168.202.129@tcp) was lost; in progress operations using this service will fail [ 6308.409381] LustreError: 279271:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6308.438277] Lustre: Unmounted lustre-client [ 6319.835873] Key type lgssc unregistered [ 6319.930332] LNet: 279665:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6319.932206] LNetError: 279665:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6319.941262] LNet: Removed LNI 192.168.202.29@tcp [ 6320.138983] Key type .llcrypt unregistered [ 6320.139848] Key type ._llcrypt unregistered [ 6324.789781] Key type ._llcrypt registered [ 6324.790780] Key type .llcrypt registered [ 6325.224107] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 00:31:41 (1763357501) [ 6325.958508] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6326.001820] alg: No test for adler32 (adler32-zlib) [ 6326.848417] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 6326.928545] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 6328.512120] Key type lgssc registered [ 6328.886249] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6344.665786] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6345.849579] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6351.458701] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6352.621484] Lustre: Mounted lustre-client [ 6357.987572] Lustre: lustre-MDT0001-mdc-ffff8cced121a000: Connection to lustre-MDT0001 (at 192.168.202.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6359.954945] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 6360.245314] LustreError: 282753:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cced121a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6360.250191] LustreError: 282753:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6360.266179] Lustre: Unmounted lustre-client [ 6371.753871] Key type lgssc unregistered [ 6371.901907] LNet: 283147:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6371.906595] LNetError: 283147:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6371.919338] LNet: Removed LNI 192.168.202.29@tcp [ 6372.296208] Key type .llcrypt unregistered [ 6372.297274] Key type ._llcrypt unregistered [ 6377.992358] Key type ._llcrypt registered [ 6377.993284] Key type .llcrypt registered [ 6378.405210] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 00:32:34 (1763357554) [ 6384.826161] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6384.835934] alg: No test for adler32 (adler32-zlib) [ 6385.706183] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 6385.801806] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 6387.408192] Key type lgssc registered [ 6387.827881] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6427.860732] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6434.269409] Lustre: Mounted lustre-client [ 6456.806669] Lustre: lustre-MDT0001-mdc-ffff8ccec5ada800: Connection to lustre-MDT0001 (at 192.168.202.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6460.205732] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6470.445939] LustreError: 288482:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 6470.478490] Lustre: Unmounted lustre-client [ 6489.743806] Key type lgssc unregistered [ 6489.911333] LNet: 288883:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6489.917334] LNetError: 288883:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6489.928416] LNet: Removed LNI 192.168.202.29@tcp [ 6490.325697] Key type .llcrypt unregistered [ 6490.327863] Key type ._llcrypt unregistered [ 6497.342680] Key type ._llcrypt registered [ 6497.345863] Key type .llcrypt registered [ 6497.962726] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 00:34:34 (1763357674) [ 6498.870253] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [ 6499.725332] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 00:34:35 (1763357675) [ 6500.606319] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [ 6501.510439] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 00:34:37 (1763357677) [ 6502.385857] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [ 6503.202755] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 00:34:39 (1763357679) [ 6504.054181] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [ 6504.907936] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 00:34:41 (1763357681) [ 6505.791943] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [ 6506.648719] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 00:34:42 (1763357682) [ 6509.807383] Key type .llcrypt unregistered [ 6509.808522] Key type ._llcrypt unregistered [ 6511.916389] Key type ._llcrypt registered [ 6511.917480] Key type .llcrypt registered [ 6512.111350] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6512.119608] alg: No test for adler32 (adler32-zlib) [ 6512.968794] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 6513.043852] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 6514.624095] Key type lgssc registered [ 6514.983024] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6543.175245] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6544.250069] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6551.862993] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6553.143594] Lustre: Mounted lustre-client [ 6553.733264] LustreError: 294460:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ccec6cb4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6553.739367] LustreError: 294460:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6553.764195] Lustre: Unmounted lustre-client [ 6578.417806] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 00:35:54 (1763357754) [ 6592.771734] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6594.590595] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6601.979358] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6607.390432] Lustre: Mounted lustre-client [ 6608.029101] LustreError: 296572:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cced013b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6608.039580] LustreError: 296572:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6608.043519] LustreError: 296572:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 6608.079695] Lustre: Unmounted lustre-client [ 6642.226372] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 00:36:58 (1763357818) [ 6661.600114] LNet: 1 local NIs in recovery (showing 1): 192.168.202.29@tcp [ 6669.123112] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6670.337076] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6676.625022] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6712.488465] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 00:38:08 (1763357888) [ 6716.024336] Key type lgssc unregistered [ 6716.141640] LNet: 302012:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6716.144144] LNetError: 302012:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6731.494578] LNet: Removed LNI 192.168.202.29@tcp [ 6731.745147] Key type .llcrypt unregistered [ 6731.746865] Key type ._llcrypt unregistered [ 6866.283046] Key type ._llcrypt registered [ 6866.284103] Key type .llcrypt registered [ 6866.460945] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6866.465055] alg: No test for adler32 (adler32-zlib) [ 6867.382119] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 6867.465881] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 6869.048159] Key type lgssc registered [ 6869.689190] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6903.082468] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6904.192955] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6912.111461] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6930.837668] Key type lgssc unregistered [ 6930.943401] LNet: 305612:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6930.945286] LNetError: 305612:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6930.954339] LNet: Removed LNI 192.168.202.29@tcp [ 6931.158357] Key type .llcrypt unregistered [ 6931.159288] Key type ._llcrypt unregistered [ 6948.702826] Key type ._llcrypt registered [ 6948.703827] Key type .llcrypt registered [ 6949.247176] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 00:42:05 (1763358125) [ 6957.379281] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6957.391637] alg: No test for adler32 (adler32-zlib) [ 6958.243398] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 6958.326082] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 6959.912201] Key type lgssc registered [ 6960.528597] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6980.110420] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 00:42:36 (1763358156) [ 6983.839263] Key type lgssc unregistered [ 6983.957386] LNet: 309154:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6983.959518] LNetError: 309154:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6983.967399] LNet: Removed LNI 192.168.202.29@tcp [ 6984.212413] Key type .llcrypt unregistered [ 6984.214235] Key type ._llcrypt unregistered [ 6988.182197] Key type ._llcrypt registered [ 6988.184550] Key type .llcrypt registered [ 6988.367833] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6988.372144] alg: No test for adler32 (adler32-zlib) [ 6989.233796] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 6989.330882] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 6990.912188] Key type lgssc registered [ 6991.481778] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7018.008498] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7019.652564] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7027.049347] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7101.995538] LNet: Added LNI 192.168.202.29@tcp50 [8/256/0/180] [ 7102.922316] LNet: Removed LNI 192.168.202.29@tcp [ 7104.440384] Lustre: 313430:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff8ccec703e800: config has no valid NIDs, force dynamic NIDs [ 7105.513608] Lustre: Mounted lustre-client [ 7105.538157] LustreError: 313445:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ccec703e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7105.544694] LustreError: 313445:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7105.570929] Lustre: Unmounted lustre-client [ 7127.067919] Key type lgssc unregistered [ 7127.222749] LNet: 314283:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7127.225217] LNetError: 314283:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7127.236265] LNet: Removed LNI 192.168.202.29@tcp50 [ 7127.475527] Key type .llcrypt unregistered [ 7127.476913] Key type ._llcrypt unregistered [ 7163.482352] Key type ._llcrypt registered [ 7163.483408] Key type .llcrypt registered [ 7163.939258] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 00:45:40 (1763358340) [ 7164.814364] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7164.845727] alg: No test for adler32 (adler32-zlib) [ 7165.734707] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 7165.847199] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 7167.432194] Key type lgssc registered [ 7168.031819] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7190.606592] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7192.244326] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7199.574041] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7209.965461] Lustre: Mounted lustre-client [ 7229.178528] Lustre: Mounted fs15246-client [ 7234.372413] LustreError: 318409:0:(lov_obd.c:783:lov_cleanup()) fs15246-clilov-ffff8ccec7614800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7234.385699] LustreError: 318409:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 7234.405503] Lustre: Unmounted fs15246-client [ 7247.346897] LustreError: 318626:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ccec5c88000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7247.352629] LustreError: 318626:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7247.355538] LustreError: 318626:0:(obd_class.h:479:obd_check_dev()) Skipped 3 previous similar messages [ 7247.377766] Lustre: Unmounted lustre-client [ 7274.599204] Key type lgssc unregistered [ 7274.723616] LNet: 319029:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7274.725637] LNetError: 319029:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7274.731721] LNet: Removed LNI 192.168.202.29@tcp [ 7274.935324] Key type .llcrypt unregistered [ 7274.936247] Key type ._llcrypt unregistered [ 7279.174811] Key type ._llcrypt registered [ 7279.175695] Key type .llcrypt registered [ 7279.727314] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 00:47:36 (1763358456) [ 7280.190262] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7280.243095] alg: No test for adler32 (adler32-zlib) [ 7281.101530] Lustre: Lustre: Build Version: 2.16.61_41_g6a6a76f [ 7281.185915] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 7282.776178] Key type lgssc registered [ 7283.328343] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7336.371487] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7337.958295] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7347.766495] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7406.848660] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 7187 sec ========== 00:49:43 (1763358583) [ 7407.663457] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 00:49:43 (1763358583) === [ 7409.178750] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 00:49:45 (1763358585) === [ 7425.072297] Key type lgssc unregistered [ 7425.217695] LNet: 328169:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7425.222330] LNetError: 328169:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7425.230964] LNet: Removed LNI 192.168.202.29@tcp [ 7425.573430] Key type .llcrypt unregistered [ 7425.574953] Key type ._llcrypt unregistered