[ 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 481197743 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.001015] APIC: Switch to symmetric I/O mode setup [ 0.002000] x2apic enabled [ 0.002000] Switched APIC routing to physical x2apic. [ 0.002017] kvm-guest: setup PV IPIs [ 0.005352] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.006000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.006025] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.007020] pid_max: default: 32768 minimum: 301 [ 0.010097] LSM: Security Framework initializing [ 0.011077] Yama: becoming mindful. [ 0.012000] SELinux: Initializing. [ 0.012113] *** VALIDATE selinux *** [ 0.021898] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.028658] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.030126] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031141] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.032122] *** VALIDATE tmpfs *** [ 0.035089] *** VALIDATE proc *** [ 0.037022] *** VALIDATE cgroup *** [ 0.038008] *** VALIDATE cgroup2 *** [ 0.040298] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.041179] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.042014] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.043037] Spectre V2 : User space: Vulnerable [ 0.044008] Speculative Store Bypass: Vulnerable [ 0.047423] debug: unmapping init [mem 0xffffffffba659000-0xffffffffba660fff] [ 0.049154] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.050763] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.051031] ... version: 2 [ 0.052014] ... bit width: 48 [ 0.053012] ... generic registers: 4 [ 0.054014] ... value mask: 0000ffffffffffff [ 0.055017] ... max period: 00007fffffffffff [ 0.056015] ... fixed-purpose events: 3 [ 0.057010] ... event mask: 000000070000000f [ 0.059194] rcu: Hierarchical SRCU implementation. [ 0.061536] smp: Bringing up secondary CPUs ... [ 0.062522] x86: Booting SMP configuration: [ 0.063026] .... node #0, CPUs: #1 #2 #3 [ 0.067761] smp: Brought up 1 node, 4 CPUs [ 0.069022] smpboot: Max logical packages: 1 [ 0.070012] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.125000] node 0 deferred pages initialised in 51ms [ 0.127108] devtmpfs: initialized [ 0.128265] x86/mm: Memory block size: 128MB [ 0.132919] gcov: version magic: 0x41383552 [ 0.135164] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.139161] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.142374] pinctrl core: initialized pinctrl subsystem [ 0.144181] [ 0.144810] ************************************************************* [ 0.147017] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.150015] ** ** [ 0.153013] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.155013] ** ** [ 0.158014] ** This means that this kernel is built to expose internal ** [ 0.161012] ** IOMMU data structures, which may compromise security on ** [ 0.163020] ** your system. ** [ 0.165015] ** ** [ 0.167010] ** If you see this message and you are not debugging the ** [ 0.170012] ** kernel, report this immediately to your vendor! ** [ 0.172013] ** ** [ 0.175014] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.177014] ************************************************************* [ 0.181688] NET: Registered protocol family 16 [ 0.183446] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.186071] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.190071] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.194088] cpuidle: using governor menu [ 0.197412] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.200612] PCI: Using configuration type 1 for base access [ 0.202125] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.206282] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.207023] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.210642] cryptd: max_cpu_qlen set to 1000 [ 0.214285] ACPI: Added _OSI(Module Device) [ 0.216017] ACPI: Added _OSI(Processor Device) [ 0.217124] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.219014] ACPI: Added _OSI(Processor Aggregator Device) [ 0.223000] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.230707] ACPI: Interpreter enabled [ 0.231062] ACPI: PM: (supports S0 S3 S4 S5) [ 0.233012] ACPI: Using IOAPIC for interrupt routing [ 0.235141] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.238410] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.248210] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.250051] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.252022] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.255086] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.261646] acpiphp: Slot [2] registered [ 0.263173] acpiphp: Slot [3] registered [ 0.265094] acpiphp: Slot [4] registered [ 0.266096] acpiphp: Slot [5] registered [ 0.267168] acpiphp: Slot [6] registered [ 0.269105] acpiphp: Slot [7] registered [ 0.270083] acpiphp: Slot [8] registered [ 0.272123] acpiphp: Slot [9] registered [ 0.273100] acpiphp: Slot [10] registered [ 0.275095] acpiphp: Slot [11] registered [ 0.277181] acpiphp: Slot [12] registered [ 0.279115] acpiphp: Slot [13] registered [ 0.280089] acpiphp: Slot [14] registered [ 0.282111] acpiphp: Slot [15] registered [ 0.283120] acpiphp: Slot [16] registered [ 0.285124] acpiphp: Slot [17] registered [ 0.287161] acpiphp: Slot [18] registered [ 0.288130] acpiphp: Slot [19] registered [ 0.290140] acpiphp: Slot [20] registered [ 0.291110] acpiphp: Slot [21] registered [ 0.293172] acpiphp: Slot [22] registered [ 0.296000] acpiphp: Slot [23] registered [ 0.297131] acpiphp: Slot [24] registered [ 0.298103] acpiphp: Slot [25] registered [ 0.301135] acpiphp: Slot [26] registered [ 0.302085] acpiphp: Slot [27] registered [ 0.303128] acpiphp: Slot [28] registered [ 0.305083] acpiphp: Slot [29] registered [ 0.306100] acpiphp: Slot [30] registered [ 0.308150] acpiphp: Slot [31] registered [ 0.309085] PCI host bridge to bus 0000:00 [ 0.311030] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.313032] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.315034] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.318038] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.320034] pci_bus 0000:00: root bus resource [mem 0x180000000-0x1ffffffff window] [ 0.324040] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.327215] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.331000] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.334000] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.339526] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.342000] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.344022] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.347025] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.350027] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.351000] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.352754] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.355043] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.357529] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.361017] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.367994] pci 0000:00:02.0: reg 0x20: [mem 0xfebf4000-0xfebf7fff 64bit pref] [ 0.371000] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.375858] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.381020] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.392017] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.411019] pci 0000:00:05.0: reg 0x20: [mem 0xfebf8000-0xfebfbfff 64bit pref] [ 0.424601] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.432021] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.439018] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.455023] pci 0000:00:06.0: reg 0x20: [mem 0xfebfc000-0xfebfffff 64bit pref] [ 0.465504] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.467323] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.469374] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.471312] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.473213] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.478050] iommu: Default domain type: Passthrough [ 0.480465] SCSI subsystem initialized [ 0.481813] ACPI: bus type USB registered [ 0.484157] usbcore: registered new interface driver usbfs [ 0.485000] usbcore: registered new interface driver hub [ 0.485000] usbcore: registered new device driver usb [ 0.487166] pps_core: LinuxPPS API ver. 1 registered [ 0.489014] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.493265] PTP clock support registered [ 0.495338] EDAC MC: Ver: 3.0.0 [ 0.499208] PCI: Using ACPI for IRQ routing [ 0.500000] NetLabel: Initializing [ 0.500020] NetLabel: domain hash size = 128 [ 0.501000] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.504094] NetLabel: unlabeled traffic allowed by default [ 0.508051] vgaarb: loaded [ 0.510525] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.513014] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.525000] clocksource: Switched to clocksource kvm-clock [ 0.640838] VFS: Disk quotas dquot_6.6.0 [ 0.645695] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.650529] *** VALIDATE ramfs *** [ 0.651579] *** VALIDATE hugetlbfs *** [ 0.656785] pnp: PnP ACPI init [ 0.659416] pnp: PnP ACPI: found 6 devices [ 0.680726] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.683240] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.685051] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.686888] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.689101] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.691224] pci_bus 0000:00: resource 8 [mem 0x180000000-0x1ffffffff window] [ 0.693594] NET: Registered protocol family 2 [ 0.695895] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.700304] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.705077] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.717572] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.730411] TCP: Hash tables configured (established 65536 bind 65536) [ 0.737381] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.741310] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.744294] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.747114] NET: Registered protocol family 1 [ 0.751101] RPC: Registered named UNIX socket transport module. [ 0.752941] RPC: Registered udp transport module. [ 0.754257] RPC: Registered tcp transport module. [ 0.755611] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.757718] NET: Registered protocol family 44 [ 0.759142] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.761098] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.762689] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.764657] PCI: CLS 0 bytes, default 64 [ 0.766873] Unpacking initramfs... [ 2.761828] debug: unmapping init [mem 0xffff8af3fcc64000-0xffff8af3fffcffff] [ 2.766063] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.768122] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.771175] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 3.563611] Initialise system trusted keyrings [ 3.565028] Key type blacklist registered [ 3.570960] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 3.583987] zbud: loaded [ 3.587607] *** VALIDATE nfs *** [ 3.588722] *** VALIDATE nfs4 *** [ 3.590132] pstore: using deflate compression [ 3.593546] Platform Keyring initialized [ 3.767299] NET: Registered protocol family 38 [ 3.768911] Key type asymmetric registered [ 3.770116] Asymmetric key parser 'x509' registered [ 3.775148] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.779564] io scheduler mq-deadline registered [ 3.780887] io scheduler kyber registered [ 3.786484] io scheduler bfq registered [ 3.788168] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.790947] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.797841] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.800590] ACPI: Power Button [PWRF] [ 4.021620] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 4.292664] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 4.537566] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 4.597520] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 4.653212] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 4.662577] Non-volatile memory driver v1.3 [ 4.665680] Linux agpgart interface v0.103 [ 4.738000] virtio_blk virtio1: [vda] 133160 512-byte logical blocks (68.2 MB/65.0 MiB) [ 4.746260] vda: detected capacity change from 0 to 68177920 [ 4.773964] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 4.782148] vdb: detected capacity change from 0 to 1073741824 [ 4.796977] libphy: Fixed MDIO Bus: probed [ 4.808398] usbcore: registered new interface driver usbserial_generic [ 4.815144] usbserial: USB Serial support registered for generic [ 4.816858] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 4.824644] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 4.825853] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 4.831169] mousedev: PS/2 mouse device common for all mice [ 4.835761] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 4.842634] rtc_cmos 00:05: RTC can wake from S4 [ 4.856199] rtc_cmos 00:05: registered as rtc0 [ 4.857177] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 4.862555] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 4.862599] intel_pstate: CPU model not supported [ 4.869112] hid: raw HID events driver (C) Jiri Kosina [ 4.880158] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 4.887199] usbcore: registered new interface driver usbhid [ 4.904693] usbhid: USB HID core driver [ 4.907180] drop_monitor: Initializing network drop monitor service [ 4.912309] Initializing XFRM netlink socket [ 4.913938] NET: Registered protocol family 10 [ 4.919388] Segment Routing with IPv6 [ 4.922727] NET: Registered protocol family 17 [ 4.926643] mpls_gso: MPLS GSO support [ 4.935469] RAS: Correctable Errors collector initialized. [ 4.937575] AVX version of gcm_enc/dec engaged. [ 4.939236] AES CTR mode by8 optimization enabled [ 5.082283] sched_clock: Marking stable (5082264605, 0)->(6200293021, -1118028416) [ 5.085899] registered taskstats version 1 [ 5.091681] Loading compiled-in X.509 certificates [ 5.094365] zswap: loaded using pool lzo/zbud [ 5.169956] Key type big_key registered [ 5.201314] Key type encrypted registered [ 5.202925] ima: No TPM chip found, activating TPM-bypass! [ 5.204942] ima: Allocated hash algorithm: sha1 [ 5.211565] ima: No architecture policies found [ 5.213174] evm: Initialising EVM extended attributes: [ 5.214795] evm: security.selinux [ 5.222347] evm: security.ima [ 5.223401] evm: security.capability [ 5.225733] evm: HMAC attrs: 0x1 [ 5.230982] rtc_cmos 00:05: setting system clock to 2025-09-24 18:03:13 UTC (1758736993) [ 5.252321] debug: unmapping init [mem 0xffffffffbb603000-0xffffffffbb7fffff] [ 5.255607] debug: unmapping init [mem 0xffffffffba382000-0xffffffffba658fff] [ 5.268110] Write protecting the kernel read-only data: 28672k [ 5.272532] debug: unmapping init [mem 0xffffffffb8a03000-0xffffffffb8bfffff] [ 5.281307] debug: unmapping init [mem 0xffffffffb9314000-0xffffffffb93fffff] [ 5.339991] 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) [ 5.353402] systemd[1]: Detected virtualization kvm. [ 5.357659] systemd[1]: Detected architecture x86-64. [ 5.359665] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 5.398210] systemd[1]: No hostname configured. [ 5.400264] systemd[1]: Set hostname to . [ 5.401928] random: systemd: uninitialized urandom read (16 bytes read) [ 5.406599] systemd[1]: Initializing machine ID from random generator. [ 5.577769] random: ln: uninitialized urandom read (6 bytes read) [ 5.885826] random: systemd: uninitialized urandom read (16 bytes read) [ 5.890292] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 5.899551] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 5.908807] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Local File Systems. [ OK ] Reached target Slices. [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on Journal Socket. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Sockets. Starting Journal Service... Starting Create Volatile Files and Directories... [ OK ] Reached target Timers. Starting Setup Virtual Console... [ OK ] Reached target Swap. Starting Apply Kernel Variables... [ OK ] Started Memstrack Anylazing Service. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 8.044352] device-mapper: uevent: version 1.0.3 [ 8.053708] 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. [ 10.335480] virtio_net virtio0 ens2: renamed from eth0 [ 10.959084] scsi host0: ata_piix [ 11.016315] scsi host1: ata_piix [ 11.030202] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 11.032801] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 16.792357] random: crng init done [ 16.793755] random: 7 urandom warning(s) missed due to ratelimiting [ 20.407051] dracut-initqueue[594]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 22.242223] 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 Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Swap. [ 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 dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 25.306427] printk: systemd: 26 output lines suppressed due to ratelimiting [ 26.399651] SELinux: Disabled at runtime. [ 26.567174] 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) [ 26.582245] systemd[1]: Detected virtualization kvm. [ 26.584500] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 28.532584] systemd[1]: initrd-switch-root.service: Succeeded. [ 28.541444] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 28.547795] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 28.565556] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 28.633444] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 28.644411] systemd[1]: Starting Journal Service... Starting Journal Service... [ 28.656287] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. Mounting Huge Pages File System... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Stopped target Switch Root. [ OK ] Created slice User and Session Slice. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Reached target Slices. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Mounting Kernel Debug File System... Starting Apply Kernel Variables... [ OK ] Reached target rpc_pipefs.target. [ OK ] Reached target Paths. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Created slice system-getty.slice. Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on udev Control Socket. [ OK ] Stopped target Initrd File Systems. Starting Remount Root and Kernel File Systems... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Stopped target Initrd Root File System. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on [ 29.377192] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS udev Kernel Socket. Mounting POSIX Message Queue File System... Starting Create list of required st…ce nodes for the current kernel... Starting udev Coldplug all Devices... [ OK ] Listening on Process Core Dump Socket. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Apply Kernel Variables. [ OK ] Started Journal Service. [ OK ] Activated swap /dev/disk/by-label/SWAP. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started udev Coldplug all Devices. [ 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 /mnt... Mounting /home/green/git/lustre-release... [ OK ] Mounted /mnt. [ 30.606806] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 31.797212] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 31.956175] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 32.920179] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 33.022408] EDAC sbridge: Ver: 1.1.2 [* ] A start job is running for Configur…-only root support (8s / no limit) [** ] A start job is running for Configur…-only root support (8s / no limit) [*** ] A start job is running for Configur…-only root support (9s / no limit)[ 37.516415] Key type dns_resolver registered [ *** ] A start job is running for Configur…-only root support (9s / no limit) [ *** ] A start job is running for Configur…only root support (10s / no limit)[ 39.010180] NFS: Registering the id_resolver key type [ 39.015170] Key type id_resolver registered [ 39.020436] Key type id_legacy registered [ ***] A start job is running for Configur…only root support (10s / no limit) [ **] A start job is running for Configur…only root support (11s / no limit) [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... Starting Rebuild Dynamic Linker Cache... 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 ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Restore /run/initramfs on shutdown... Starting Login Service... [ OK ] Reached target sshd-keygen.target. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started irqbalance daemon. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Started Login Service. Starting Hostname Service... [ 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 Crash recovery kernel arming... Starting System Logging Service... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg440-client login: [ 101.923586] libcfs: loading out-of-tree module taints kernel. [ 102.004719] Key type ._llcrypt registered [ 102.006127] Key type .llcrypt registered [ 102.397019] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 102.408778] alg: No test for adler32 (adler32-zlib) [ 103.600870] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 104.288287] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 106.000409] Key type lgssc registered [ 107.351278] Lustre: Echo OBD driver; http://www.lustre.org/ [ 124.867096] hrtimer: interrupt took 2252658 ns [ 220.400191] Lustre: Mounted lustre-client [ 224.508640] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 239.093069] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing check_logdir /tmp/testlogs/ [ 243.683439] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing yml_node [ 246.239167] Lustre: lustre-OST0000-osc-ffff8af4479f8000: disconnect after 24s idle [ 247.818632] Lustre: DEBUG MARKER: Client: 2.16.58.53 [ 249.881855] Lustre: DEBUG MARKER: MDS: 2.16.58.53 [ 252.007454] Lustre: DEBUG MARKER: OSS: 2.16.58.53 [ 253.078816] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Wed Sep 24 14:07:20 EDT 2025 [ 267.266333] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 268.688220] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 270.181634] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 280.429317] LustreError: 8994:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 280.470755] Lustre: Unmounted lustre-client [ 380.575257] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 383.667411] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 401.055415] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 431.586728] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 14:10:18 (1758737418) [ 433.484979] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 434.686420] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 14:10:21 (1758737421) [ 454.448562] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 456.611531] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 468.219118] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 468.527259] Lustre: Mounted lustre-client [ 474.691473] LustreError: 14434:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af449a04000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 474.707779] LustreError: 14434:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 474.711790] LustreError: 14434:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 474.757091] Lustre: Unmounted lustre-client [ 498.968697] Key type lgssc unregistered [ 499.218592] LNet: 14841:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 499.224740] LNetError: 14841:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 499.246260] LNet: Removed LNI 192.168.204.40@tcp [ 499.874298] Key type .llcrypt unregistered [ 499.876080] Key type ._llcrypt unregistered [ 512.705536] Key type ._llcrypt registered [ 512.707580] Key type .llcrypt registered [ 513.739953] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 14:11:40 (1758737500) [ 514.651744] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 514.734749] alg: No test for adler32 (adler32-zlib) [ 515.811332] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 516.020092] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 517.735197] Key type lgssc registered [ 518.772391] Lustre: Echo OBD driver; http://www.lustre.org/ [ 578.555760] Lustre: Mounted lustre-client [ 660.754147] LustreError: 18157:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af445287800: lov tgt 1990 not cleaned! deathrow=0, lovrc=1 [ 660.766611] LustreError: 18157:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 660.807844] Lustre: Unmounted lustre-client [ 685.172779] Key type lgssc unregistered [ 685.426081] LNet: 18619:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 685.437059] LNetError: 18619:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 685.453773] LNet: Removed LNI 192.168.204.40@tcp [ 686.060604] Key type .llcrypt unregistered [ 686.063331] Key type ._llcrypt unregistered [ 694.093646] Key type ._llcrypt registered [ 694.097961] Key type .llcrypt registered [ 695.564939] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 14:14:42 (1758737682) [ 697.664946] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 697.688207] alg: No test for adler32 (adler32-zlib) [ 698.788762] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 698.986292] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 700.647166] Key type lgssc registered [ 701.687291] Lustre: Echo OBD driver; http://www.lustre.org/ [ 735.295836] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 737.726790] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 752.736356] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 755.236688] Lustre: Mounted lustre-client [ 763.550903] LustreError: 21725:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af447246800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 763.574951] LustreError: 21725:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 763.629335] Lustre: Unmounted lustre-client [ 786.758879] Key type lgssc unregistered [ 786.990655] LNet: 22127:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 786.995325] LNetError: 22127:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 787.010199] LNet: Removed LNI 192.168.204.40@tcp [ 787.649117] Key type .llcrypt unregistered [ 787.651775] Key type ._llcrypt unregistered [ 795.677392] Key type ._llcrypt registered [ 795.686470] Key type .llcrypt registered [ 796.789247] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 14:16:23 (1758737783) [ 798.463497] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 798.492616] alg: No test for adler32 (adler32-zlib) [ 799.576515] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 799.751350] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 801.439861] Key type lgssc registered [ 802.537543] Lustre: Echo OBD driver; http://www.lustre.org/ [ 845.551572] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 848.618744] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 864.040737] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 870.990324] Lustre: Mounted lustre-client [ 875.524765] LustreError: 24827:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af4479fb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 875.547390] LustreError: 24827:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 875.595178] Lustre: Unmounted lustre-client [ 901.047930] Key type lgssc unregistered [ 901.319169] LNet: 25227:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 901.350468] LNetError: 25227:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 901.393804] LNet: Removed LNI 192.168.204.40@tcp [ 901.993735] Key type .llcrypt unregistered [ 901.997193] Key type ._llcrypt unregistered [ 914.939038] Key type ._llcrypt registered [ 914.942415] Key type .llcrypt registered [ 916.015592] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 14:18:23 (1758737903) [ 918.147933] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 918.182446] alg: No test for adler32 (adler32-zlib) [ 919.308970] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 919.601551] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 921.263177] Key type lgssc registered [ 922.492483] Lustre: Echo OBD driver; http://www.lustre.org/ [ 962.606272] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 966.047288] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 981.543351] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 992.289522] Lustre: Mounted lustre-client [ 997.352454] Lustre: lustre-MDT0000-mdc-ffff8af445217000: Connection to lustre-MDT0000 (at 192.168.204.140@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1002.471997] Lustre: lustre-MDT0001-mdc-ffff8af445217000: Connection to lustre-MDT0001 (at 192.168.204.140@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1009.941830] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1018.655987] Lustre: 26184:0:(client.c:2464:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1758737990/real 1758737990] req@000000000a859aaa x1844170359576576/t0(0) o400->MGC192.168.204.140@tcp@192.168.204.140@tcp:26/25 lens 224/224 e 0 to 1 dl 1758738006 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1018.673965] LustreError: MGC192.168.204.140@tcp: Connection to MGS (at 192.168.204.140@tcp) was lost; in progress operations using this service will fail [ 1020.217035] LustreError: 28403:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1020.259994] Lustre: Unmounted lustre-client [ 1037.053339] Key type lgssc unregistered [ 1037.273202] LNet: 28793:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1037.282545] LNetError: 28793:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1038.312562] LNet: Removed LNI 192.168.204.40@tcp [ 1039.110231] Key type .llcrypt unregistered [ 1039.111852] Key type ._llcrypt unregistered [ 1051.036247] Key type ._llcrypt registered [ 1051.041029] Key type .llcrypt registered [ 1052.233598] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 14:20:39 (1758738039) [ 1054.147327] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1054.211883] alg: No test for adler32 (adler32-zlib) [ 1055.288835] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 1055.511720] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 1057.191190] Key type lgssc registered [ 1058.261268] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1099.095458] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1101.751127] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1117.219469] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1123.875917] Lustre: Mounted lustre-client [ 1128.946784] Lustre: lustre-OST0000-osc-ffff8af4479fa000: Connection to lustre-OST0000 (at 192.168.204.140@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1135.424110] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1145.714985] LustreError: 31914:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1145.742828] Lustre: Unmounted lustre-client [ 1167.243525] Key type lgssc unregistered [ 1167.431206] LNet: 32315:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1167.438850] LNetError: 32315:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1167.454324] LNet: Removed LNI 192.168.204.40@tcp [ 1167.887955] Key type .llcrypt unregistered [ 1167.889785] Key type ._llcrypt unregistered [ 1179.820390] Key type ._llcrypt registered [ 1179.827290] Key type .llcrypt registered [ 1180.953803] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 14:22:47 (1758738167) [ 1182.886444] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1182.903910] alg: No test for adler32 (adler32-zlib) [ 1183.961950] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 1184.170787] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 1185.815211] Key type lgssc registered [ 1186.824762] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1229.124086] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1232.326608] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1250.015428] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1251.699663] Lustre: Mounted lustre-client [ 1255.092667] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1258.360415] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1269.821616] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2025-09-2414h24m16s [ 1271.658716] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1277.415887] Lustre: lustre-MDT0000-mdc-ffff8af4503bb800: Connection to lustre-MDT0000 (at 192.168.204.140@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1281.530169] LustreError: 36019:0:(lmv_obd.c:1434:lmv_statfs()) lustre-MDT0000-mdc-ffff8af4503bb800: can't stat MDS #0: rc = -19 [ 1282.843251] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1288.679353] LustreError: MGC192.168.204.140@tcp: Connection to MGS (at 192.168.204.140@tcp) was lost; in progress operations using this service will fail [ 1288.691526] Lustre: Evicted from MGS (at 192.168.204.140@tcp) after server handle changed from 0x8fe8651ecc535557 to 0x8fe8651ecc5357f0 [ 1288.697409] Lustre: MGC192.168.204.140@tcp: Connection restored to 192.168.204.140@tcp (at 192.168.204.140@tcp) [ 1289.704219] LustreError: lustre-MDT0000-mdc-ffff8af4503bb800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1289.738296] Lustre: lustre-MDT0000-mdc-ffff8af4503bb800: Connection restored to 192.168.204.140@tcp (at 192.168.204.140@tcp) [ 1293.836748] Lustre: DEBUG MARKER: Wait for df (36019) ... [ 1295.199490] Lustre: DEBUG MARKER: done [ 1296.290524] LustreError: 36634:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af4503bb800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1296.300647] LustreError: 36634:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1296.338763] Lustre: Unmounted lustre-client [ 1328.819049] Key type lgssc unregistered [ 1329.024923] LNet: 37037:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1329.034428] LNetError: 37037:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1329.051346] LNet: Removed LNI 192.168.204.40@tcp [ 1329.564769] Key type .llcrypt unregistered [ 1329.566562] Key type ._llcrypt unregistered [ 1364.125797] Key type ._llcrypt registered [ 1364.127102] Key type .llcrypt registered [ 1364.494972] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1364.506381] alg: No test for adler32 (adler32-zlib) [ 1365.509502] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 1365.673959] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 1367.335128] Key type lgssc registered [ 1368.396410] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1426.129325] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1428.907657] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1445.982822] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1483.970791] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 14:27:51 (1758738471) [ 1510.552183] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1513.702886] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1528.910325] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1540.130670] Lustre: Mounted lustre-client [ 1544.572142] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2025-09-2414h28m51s [ 1545.661303] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1551.276536] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1553.174154] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1573.343561] Lustre: 43912:0:(client.c:2464:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1758738541/real 1758738541] req@00000000096c4fc0 x1844170827241088/t0(0) o101->lustre-MDT0000-mdc-ffff8af445284800@192.168.204.140@tcp:12/10 lens 576/1152 e 0 to 1 dl 1758738561 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:0 [ 1573.358825] Lustre: lustre-MDT0000-mdc-ffff8af445284800: Connection to lustre-MDT0000 (at 192.168.204.140@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1573.430630] Lustre: lustre-MDT0000-mdc-ffff8af445284800: Connection restored to 192.168.204.140@tcp (at 192.168.204.140@tcp) [ 1594.479804] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1596.761562] Lustre: DEBUG MARKER: done [ 1599.272784] LustreError: 44386:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af445284800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1599.283218] LustreError: 44386:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1599.339800] Lustre: Unmounted lustre-client [ 1623.569276] Key type lgssc unregistered [ 1623.843524] LNet: 44789:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1623.850897] LNetError: 44789:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1623.867124] LNet: Removed LNI 192.168.204.40@tcp [ 1624.404779] Key type .llcrypt unregistered [ 1624.406916] Key type ._llcrypt unregistered [ 1661.789147] Key type ._llcrypt registered [ 1661.790830] Key type .llcrypt registered [ 1662.130632] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1662.142721] alg: No test for adler32 (adler32-zlib) [ 1663.198710] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 1663.372775] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 1665.015195] Key type lgssc registered [ 1666.006718] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1725.156858] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1728.106289] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1746.294329] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1791.789630] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 14:32:58 (1758738778) [ 1792.877596] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1794.297767] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 14:33:01 (1758738781) [ 1814.110880] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 14:33:21 (1758738801) [ 1836.949622] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1840.708941] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1855.673604] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1866.770950] Lustre: Mounted lustre-client [ 1869.832837] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 1872.611706] LustreError: 52625:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af4503bf800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1872.637737] LustreError: 52625:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1872.693810] Lustre: Unmounted lustre-client [ 1885.234926] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 1925.246558] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1928.029224] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1928.589165] Lustre: Mounted lustre-client [ 1931.321403] LustreError: 53890:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af4479fa800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1931.346982] LustreError: 53890:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1931.349552] LustreError: 53890:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 1931.397545] Lustre: Unmounted lustre-client [ 1981.647854] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1984.328945] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1984.829956] Lustre: Mounted lustre-client [ 1987.369763] LustreError: 55010:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af445282000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1987.401766] LustreError: 55010:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1987.405063] LustreError: 55010:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 1987.486159] Lustre: Unmounted lustre-client [ 2006.058801] Lustre: DEBUG MARKER: files compared the same [ 2028.418416] Key type lgssc unregistered [ 2028.691725] LNet: 55713:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2028.699897] LNetError: 55713:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2028.710606] LNet: Removed LNI 192.168.204.40@tcp [ 2029.316128] Key type .llcrypt unregistered [ 2029.320226] Key type ._llcrypt unregistered [ 2043.617376] Key type ._llcrypt registered [ 2043.619029] Key type .llcrypt registered [ 2044.982339] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 14:37:11 (1758739031) [ 2047.126532] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2047.274115] alg: No test for adler32 (adler32-zlib) [ 2048.416961] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 2048.618744] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 2050.271165] Key type lgssc registered [ 2051.326293] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2092.545271] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2096.279683] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2114.166833] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2120.240438] Lustre: Mounted lustre-client [ 2123.527925] LustreError: 58805:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af449a04800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2123.547104] LustreError: 58805:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2123.623430] Lustre: Unmounted lustre-client [ 2148.515196] Key type lgssc unregistered [ 2148.827485] LNet: 59207:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2148.839593] LNetError: 59207:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2148.861143] LNet: Removed LNI 192.168.204.40@tcp [ 2149.513101] Key type .llcrypt unregistered [ 2149.514793] Key type ._llcrypt unregistered [ 2162.908826] Key type ._llcrypt registered [ 2162.910374] Key type .llcrypt registered [ 2164.103934] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 14:39:11 (1758739151) [ 2165.877126] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2165.906180] alg: No test for adler32 (adler32-zlib) [ 2166.938367] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 2167.164445] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 2168.839223] Key type lgssc registered [ 2169.919731] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2252.240279] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2279.756719] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2283.236622] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2308.510781] Key type lgssc unregistered [ 2308.735678] LNet: 62603:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2308.746415] LNetError: 62603:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2308.783759] LNet: Removed LNI 192.168.204.40@tcp [ 2309.450387] Key type .llcrypt unregistered [ 2309.452426] Key type ._llcrypt unregistered [ 2322.805776] Key type ._llcrypt registered [ 2322.807445] Key type .llcrypt registered [ 2323.957449] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 14:41:50 (1758739310) [ 2326.679235] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2326.715346] alg: No test for adler32 (adler32-zlib) [ 2327.781278] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 2327.984735] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 2329.639181] Key type lgssc registered [ 2330.764483] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2393.141680] Lustre: Mounted lustre-client [ 2399.430414] LustreError: 65089:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af44457a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2399.452371] LustreError: 65089:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2399.501224] Lustre: Unmounted lustre-client [ 2442.286125] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 14:43:49 (1758739429) [ 2551.464817] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2569.269558] Lustre: Mounted lustre-client [ 2575.668434] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2575.697128] LustreError: 69051:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff8af44a0aa800: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 2575.725252] Lustre: 63583:0:(llite_lib.c:4240:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.204.140@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 2575.733735] LustreError: 69051:0:(ldlm_resource.c:981:ldlm_resource_complain()) lustre-OST0000-osc-ffff8af44a0aa800: namespace resource [0x240000401:0x2:0x0].0x0 (000000002b4d00db) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 2586.031034] LustreError: 69051:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2586.058600] LustreError: 69051:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 2586.137201] Lustre: Unmounted lustre-client [ 2637.735292] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 14:47:04 (1758739624) [ 2649.325643] Key type lgssc unregistered [ 2649.641626] LNet: 70264:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2649.648363] LNetError: 70264:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2649.668604] LNet: Removed LNI 192.168.204.40@tcp [ 2650.364304] Key type .llcrypt unregistered [ 2650.371994] Key type ._llcrypt unregistered [ 2662.116023] Key type ._llcrypt registered [ 2662.128510] Key type .llcrypt registered [ 2662.726477] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2662.745643] alg: No test for adler32 (adler32-zlib) [ 2664.040476] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 2664.337947] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 2666.015271] Key type lgssc registered [ 2667.382366] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2713.699209] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2717.846456] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2736.542550] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2738.188691] Lustre: Mounted lustre-client [ 2741.851303] LustreError: 72783:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af446d5e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2741.871184] LustreError: 72783:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2741.921968] Lustre: Unmounted lustre-client [ 2769.403767] Key type lgssc unregistered [ 2769.792665] LNet: 73186:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2769.811168] LNetError: 73186:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2769.839156] LNet: Removed LNI 192.168.204.40@tcp [ 2770.933463] Key type .llcrypt unregistered [ 2770.937321] Key type ._llcrypt unregistered [ 2780.268707] Key type ._llcrypt registered [ 2780.286696] Key type .llcrypt registered [ 2780.641745] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2780.653964] alg: No test for adler32 (adler32-zlib) [ 2781.770983] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 2782.078264] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 2783.751182] Key type lgssc registered [ 2784.794053] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2881.102507] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2884.195866] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2899.427597] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2911.296833] Lustre: Mounted lustre-client [ 2913.078405] LustreError: 76124:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af445282800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2913.104158] LustreError: 76124:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2913.178646] Lustre: Unmounted lustre-client [ 2939.656862] Key type lgssc unregistered [ 2939.991816] LNet: 76527:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2939.996062] LNetError: 76527:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2940.010689] LNet: Removed LNI 192.168.204.40@tcp [ 2940.635702] Key type .llcrypt unregistered [ 2940.640882] Key type ._llcrypt unregistered [ 2952.828245] Key type ._llcrypt registered [ 2952.829840] Key type .llcrypt registered [ 2954.048259] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 14:52:20 (1758739940) [ 2955.860611] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2955.894723] alg: No test for adler32 (adler32-zlib) [ 2956.912434] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 2957.098878] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 2958.807228] Key type lgssc registered [ 2960.004725] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2999.115838] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3002.544414] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3018.830364] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3025.997701] Lustre: Mounted lustre-client [ 3030.300960] LustreError: 79644:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af45037e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3030.328305] LustreError: 79644:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3030.412139] Lustre: Unmounted lustre-client [ 3030.899253] Lustre: Mounted lustre-client [ 3033.052421] LustreError: 79721:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af4459a4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3033.084934] LustreError: 79721:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3033.093626] LustreError: 79721:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 3033.193561] Lustre: Unmounted lustre-client [ 3058.458645] Key type lgssc unregistered [ 3058.748836] LNet: 80123:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3058.754546] LNetError: 80123:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3058.772811] LNet: Removed LNI 192.168.204.40@tcp [ 3059.439676] Key type .llcrypt unregistered [ 3059.442576] Key type ._llcrypt unregistered [ 3069.698941] Key type ._llcrypt registered [ 3069.701404] Key type .llcrypt registered [ 3070.047174] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3070.072921] alg: No test for adler32 (adler32-zlib) [ 3071.077819] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 3071.313477] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 3072.975875] Key type lgssc registered [ 3074.111411] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3109.560394] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3112.595144] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3125.848712] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3141.655702] Lustre: Mounted lustre-client [ 3144.213727] LustreError: 82660:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af44b370000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3144.230609] LustreError: 82660:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3144.263092] Lustre: Unmounted lustre-client [ 3174.982290] Key type lgssc unregistered [ 3175.165332] LNet: 83063:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3175.169471] LNetError: 83063:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3175.181906] LNet: Removed LNI 192.168.204.40@tcp [ 3175.661444] Key type .llcrypt unregistered [ 3175.664513] Key type ._llcrypt unregistered [ 3184.448754] Key type ._llcrypt registered [ 3184.450676] Key type .llcrypt registered [ 3185.245774] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 14:56:12 (1758740172) [ 3186.778290] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3186.815717] alg: No test for adler32 (adler32-zlib) [ 3187.834385] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 3188.039594] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 3189.687130] Key type lgssc registered [ 3190.465421] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3220.025964] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3222.405397] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3234.261565] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3236.753360] Lustre: Mounted lustre-client [ 3243.767190] Lustre: lustre: root_squash is set to 0:0 [ 3249.036107] Lustre: lustre: nosquash_nids is cleared [ 3257.377428] Lustre: lustre: root_squash is set to 500:500 [ 3266.571242] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.204.40@tcp 192.168.0.[2,10]@tcp [ 3275.776652] Lustre: lustre: nosquash_nids set to 192.168.204.40/24@tcp [ 3277.783580] LustreError: 86554:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af442c48000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3277.803593] LustreError: 86554:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3277.850135] Lustre: Unmounted lustre-client [ 3300.959329] Key type lgssc unregistered [ 3301.185396] LNet: 86956:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3301.199860] LNetError: 86956:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3301.231347] LNet: Removed LNI 192.168.204.40@tcp [ 3301.820244] Key type .llcrypt unregistered [ 3301.821535] Key type ._llcrypt unregistered [ 3313.145128] Key type ._llcrypt registered [ 3313.147447] Key type .llcrypt registered [ 3314.093661] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 14:58:21 (1758740301) [ 3315.669754] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3316.900288] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 14:58:24 (1758740304) [ 3318.289600] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3318.313211] alg: No test for adler32 (adler32-zlib) [ 3319.266077] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 3319.415312] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 3321.031570] Key type lgssc registered [ 3322.053667] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3354.841652] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3357.312512] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3369.795635] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3371.187359] Lustre: lustre: root_squash is set to 500:500 [ 3371.215308] Lustre: lustre: nosquash_nids set to 192.168.204.40/24@tcp [ 3372.285242] Lustre: Mounted lustre-client [ 3379.445162] LustreError: 90418:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af44986e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3379.459557] LustreError: 90418:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3379.523996] Lustre: Unmounted lustre-client [ 3411.042593] Key type lgssc unregistered [ 3411.269237] LNet: 90820:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3411.279737] LNetError: 90820:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3411.291834] LNet: Removed LNI 192.168.204.40@tcp [ 3411.791107] Key type .llcrypt unregistered [ 3411.794571] Key type ._llcrypt unregistered [ 3421.860791] Key type ._llcrypt registered [ 3421.864773] Key type .llcrypt registered [ 3422.758971] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 3423.887852] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 15:00:11 (1758740411) [ 3434.269040] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3434.283704] alg: No test for adler32 (adler32-zlib) [ 3435.198922] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 3435.323690] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 3436.943225] Key type lgssc registered [ 3437.591763] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3484.716316] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3486.932755] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3499.522890] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3548.293270] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3550.392437] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3560.751185] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3570.478551] Lustre: Mounted lustre-client [ 3584.995647] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8af4506e9000.ost_server_uuid 50 [ 3585.741487] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8af4506e9000.ost_server_uuid in FULL state after 0 sec [ 3586.031716] Lustre: Mounted lustre-client [ 3587.750600] LustreError: 97723:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af44593d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3587.766213] LustreError: 97723:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 3587.810446] Lustre: Unmounted lustre-client [ 3588.368114] LustreError: 97744:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af4506e9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3588.374174] LustreError: 97744:0:(lov_obd.c:784:lov_cleanup()) Skipped 1 previous similar message [ 3588.385362] LustreError: 97744:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 3588.389296] LustreError: 97744:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 3588.419670] Lustre: Unmounted lustre-client [ 3620.875712] Key type lgssc unregistered [ 3621.038063] LNet: 98295:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3621.043072] LNetError: 98295:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3621.054792] LNet: Removed LNI 192.168.204.40@tcp [ 3621.400977] Key type .llcrypt unregistered [ 3621.404396] Key type ._llcrypt unregistered [ 3643.564224] Key type ._llcrypt registered [ 3643.567769] Key type .llcrypt registered [ 3643.757911] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3643.764186] alg: No test for adler32 (adler32-zlib) [ 3644.664613] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 3644.768497] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 3646.375276] Key type lgssc registered [ 3646.898431] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3688.008171] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3689.792652] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3700.690909] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3730.447274] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 15:05:17 (1758740717) [ 3774.096948] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3775.664935] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3785.724822] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3786.942331] Lustre: Mounted lustre-client [ 3802.596680] Lustre: lustre-OST0000-osc-ffff8af4506e9000: Connection to lustre-OST0000 (at 192.168.204.140@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3807.716458] Lustre: lustre-MDT0000-mdc-ffff8af4506e9000: Connection to lustre-MDT0000 (at 192.168.204.140@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3808.735206] Lustre: 99080:0:(client.c:2464:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1758740780/real 1758740780] req@000000000ca9b40a x1844173217993856/t0(0) o400->lustre-OST0000-osc-ffff8af4506e9000@192.168.204.140@tcp:28/4 lens 224/224 e 0 to 1 dl 1758740796 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3812.831611] Lustre: 99081:0:(client.c:2464:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1758740785/real 1758740785] req@00000000d16c67b6 x1844173217994368/t0(0) o400->lustre-OST0000-osc-ffff8af4506e9000@192.168.204.140@tcp:28/4 lens 224/224 e 0 to 1 dl 1758740801 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3823.071239] Lustre: 99079:0:(client.c:2464:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1758740795/real 1758740795] req@000000003f208fa5 x1844173217995264/t0(0) o400->MGC192.168.204.140@tcp@192.168.204.140@tcp:26/25 lens 224/224 e 0 to 1 dl 1758740811 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3823.077782] LustreError: MGC192.168.204.140@tcp: Connection to MGS (at 192.168.204.140@tcp) was lost; in progress operations using this service will fail [ 3823.102085] Lustre: 106215:0:(mgc_request.c:1900:mgc_process_log()) MGC192.168.204.140@tcp: IR log lustre-cliir failed, not fatal: rc = -5 [ 3823.111240] Lustre: Evicted from MGS (at 192.168.204.140@tcp) after server handle changed from 0xb76d88f922809d1b to 0xb76d88f922809fb4 [ 3823.118422] Lustre: MGC192.168.204.140@tcp: Connection restored to 192.168.204.140@tcp (at 192.168.204.140@tcp) [ 3826.556309] Lustre: lustre-MDT0000-mdc-ffff8af4506e9000: Connection restored to 192.168.204.140@tcp (at 192.168.204.140@tcp) [ 3827.143121] LustreError: 106937:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af4506e9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3827.151887] LustreError: 106937:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3827.179210] Lustre: Unmounted lustre-client [ 3846.117428] Key type lgssc unregistered [ 3846.284900] LNet: 107338:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3846.287986] LNetError: 107338:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3846.297758] LNet: Removed LNI 192.168.204.40@tcp [ 3846.659253] Key type .llcrypt unregistered [ 3846.660698] Key type ._llcrypt unregistered [ 3853.439273] Key type ._llcrypt registered [ 3853.440913] Key type .llcrypt registered [ 3854.113575] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 15:07:21 (1758740841) [ 3866.386467] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3866.398539] alg: No test for adler32 (adler32-zlib) [ 3867.298611] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 3867.427573] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 3869.031250] Key type lgssc registered [ 3869.602754] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3888.866225] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3931.645488] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3933.336779] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3942.996702] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3944.215836] Lustre: Mounted lustre-client [ 3944.982562] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 3945.655443] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 15:08:53 (1758740933) [ 4025.149790] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4035.430405] LustreError: 118073:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4035.450362] Lustre: Unmounted lustre-client [ 4092.284544] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4093.669357] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4102.646189] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4122.023708] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 15:11:49 (1758741109) [ 4159.468170] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4160.868343] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4169.216219] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4174.837991] Lustre: Mounted lustre-client [ 4176.136155] LustreError: 125464:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af446eb1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4176.144889] LustreError: 125464:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4176.147732] LustreError: 125464:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4176.175414] Lustre: Unmounted lustre-client [ 4187.552035] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 15:12:55 (1758741175) [ 4225.010508] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4226.491624] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4235.253431] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4235.454143] Lustre: Mounted lustre-client [ 4236.657765] LustreError: 129497:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af447246000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4236.668189] LustreError: 129497:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4236.671339] LustreError: 129497:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4236.697181] Lustre: Unmounted lustre-client [ 4254.723195] Key type lgssc unregistered [ 4254.863373] LNet: 129898:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4254.867563] LNetError: 129898:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4254.877315] LNet: Removed LNI 192.168.204.40@tcp [ 4255.156359] Key type .llcrypt unregistered [ 4255.158078] Key type ._llcrypt unregistered [ 4261.131653] Key type ._llcrypt registered [ 4261.133307] Key type .llcrypt registered [ 4261.702897] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 15:14:09 (1758741249) [ 4262.606775] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4262.626505] alg: No test for adler32 (adler32-zlib) [ 4263.507254] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 4263.608283] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 4265.207175] Key type lgssc registered [ 4265.683018] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4286.490960] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4287.944426] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4295.463801] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4305.909115] Lustre: Mounted lustre-client [ 4316.800176] LustreError: 132992:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af44a4e5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4316.809238] LustreError: 132992:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4316.838383] Lustre: Unmounted lustre-client [ 4344.079395] Key type lgssc unregistered [ 4344.222071] LNet: 133394:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4344.225657] LNetError: 133394:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4344.235910] LNet: Removed LNI 192.168.204.40@tcp [ 4344.554439] Key type .llcrypt unregistered [ 4344.555971] Key type ._llcrypt unregistered [ 4351.499076] Key type ._llcrypt registered [ 4351.500522] Key type .llcrypt registered [ 4352.153218] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 15:15:39 (1758741339) [ 4353.101715] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4353.142616] alg: No test for adler32 (adler32-zlib) [ 4354.050513] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 4354.148900] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 4355.751195] Key type lgssc registered [ 4356.311972] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4378.029856] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4379.639845] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4387.680529] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4393.974462] Lustre: Mounted lustre-client [ 4399.076849] Lustre: lustre-OST0000-osc-ffff8af44795e000: Connection to lustre-OST0000 (at 192.168.204.140@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4404.425156] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff8af44795e000.ost_server_uuid 50 [ 4405.103839] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 4414.511529] LustreError: 136920:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af44795e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4414.518273] LustreError: 136920:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4414.544181] Lustre: Unmounted lustre-client [ 4426.801824] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 15:16:54 (1758741414) [ 4438.942984] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4440.373199] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4447.710973] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4456.833258] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4457.070283] Lustre: Mounted lustre-client [ 4462.563431] Lustre: lustre-OST0000-osc-ffff8af445281000: Connection to lustre-OST0000 (at 192.168.204.140@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4477.385859] LustreError: 139566:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af445281000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4477.394704] LustreError: 139566:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 4477.397510] LustreError: 139566:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4477.423138] Lustre: Unmounted lustre-client [ 4511.148314] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 15:18:18 (1758741498) [ 4522.507283] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4523.980025] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4531.134289] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4538.615268] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4538.864516] Lustre: Mounted lustre-client [ 4543.970950] Lustre: lustre-OST0000-osc-ffff8af449f49000: Connection to lustre-OST0000 (at 192.168.204.140@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4564.181622] LustreError: 142321:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af449f49000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4564.185347] LustreError: 142321:0:(lov_obd.c:784:lov_cleanup()) Skipped 1 previous similar message [ 4564.190698] LustreError: 142321:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 4564.192875] LustreError: 142321:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 4564.215638] Lustre: Unmounted lustre-client [ 4588.765947] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 15:19:36 (1758741576) [ 4620.949975] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4622.226840] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4629.984809] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4661.155932] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4662.401378] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4668.816400] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4691.018768] Lustre: Mounted lustre-client [ 4719.037705] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4719.715817] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 4720.138914] LustreError: 148903:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af44a4e5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4720.143925] LustreError: 148903:0:(lov_obd.c:784:lov_cleanup()) Skipped 1 previous similar message [ 4720.151318] LustreError: 148903:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4720.153870] LustreError: 148903:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 4720.181193] Lustre: Unmounted lustre-client [ 4732.015698] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 15:21:59 (1758741719) [ 4743.074886] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4744.571542] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4751.548488] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4770.636492] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4784.500378] Lustre: Mounted lustre-client [ 4812.640614] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4813.362494] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 4815.333349] Lustre: lustre-OST0001-osc-ffff8af44442a800: Connection to lustre-OST0001 (at 192.168.204.140@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4817.570620] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4827.815554] LustreError: 152478:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 4827.818703] LustreError: 152478:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4827.841223] Lustre: Unmounted lustre-client [ 4868.976512] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 15:24:16 (1758741856) [ 4880.254538] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4881.588216] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4888.314251] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4893.697746] Lustre: Mounted lustre-client [ 4902.347358] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4906.035196] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 3 sec [ 4910.886676] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8af44b6f7000.ost_server_uuid 50 [ 4911.476654] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8af44b6f7000.ost_server_uuid in FULL state after 0 sec [ 4912.235120] LustreError: 155916:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af44b6f7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4912.242724] LustreError: 155916:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 4912.245461] LustreError: 155916:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 4912.271118] Lustre: Unmounted lustre-client [ 4912.385490] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 4912.388408] Lustre: Skipped 3 previous similar messages [ 4912.435726] Lustre: Mounted lustre-client [ 4912.912220] LustreError: 155980:0:(sec.c:411:import_sec_validate_get()) import 00000000855e6132 (NEW) with no sec [ 4953.876666] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 15:25:41 (1758741941) [ 4965.197570] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4966.395124] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4972.620574] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4979.360607] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4979.499431] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 4984.821180] Lustre: Mounted lustre-client [ 4987.478070] LustreError: 158787:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af444411800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4987.483385] LustreError: 158787:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 4987.496272] LustreError: 158787:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 4987.498414] LustreError: 158787:0:(obd_class.h:479:obd_check_dev()) Skipped 18 previous similar messages [ 4987.525813] Lustre: Unmounted lustre-client [ 4987.526990] Lustre: Skipped 1 previous similar message [ 5029.810117] Key type lgssc unregistered [ 5029.948039] LNet: 159237:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5029.951743] LNetError: 159237:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5029.962768] LNet: Removed LNI 192.168.204.40@tcp [ 5030.266696] Key type .llcrypt unregistered [ 5030.268285] Key type ._llcrypt unregistered [ 5035.775546] Key type ._llcrypt registered [ 5035.777084] Key type .llcrypt registered [ 5036.296351] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 15:27:03 (1758742023) [ 5036.592993] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5036.615874] alg: No test for adler32 (adler32-zlib) [ 5037.491560] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 5037.574535] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 5039.167148] Key type lgssc registered [ 5039.567099] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5061.281352] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5062.727116] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5070.660201] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5079.571717] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5079.749577] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 5080.809650] Lustre: Mounted lustre-client [ 5088.753231] LustreError: lustre-MDT0001-mdc-ffff8af44442d800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 5088.760505] Lustre: lustre-MDT0001-mdc-ffff8af44442d800: Connection restored to 192.168.204.140@tcp (at 192.168.204.140@tcp) [ 5091.152854] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5091.895792] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 5098.665088] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 5110.149440] LustreError: 163461:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af44442d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5110.154674] LustreError: 163461:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 5110.178101] Lustre: Unmounted lustre-client [ 5130.358386] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 15:28:38 (1758742118) [ 5166.657695] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5168.153908] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5176.367603] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5176.589136] Lustre: Mounted lustre-client [ 5187.163113] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5187.925369] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 5200.869796] Lustre: lustre-OST0001-osc-ffff8af44593a800: Connection to lustre-OST0001 (at 192.168.204.140@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5205.332276] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5215.593069] LustreError: 168185:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 5215.596281] LustreError: 168185:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 5215.611360] Lustre: Unmounted lustre-client [ 5248.649863] Key type lgssc unregistered [ 5248.789208] LNet: 168590:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5248.792289] LNetError: 168590:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5248.802770] LNet: Removed LNI 192.168.204.40@tcp [ 5249.120939] Key type .llcrypt unregistered [ 5249.122768] Key type ._llcrypt unregistered [ 5265.310810] Key type ._llcrypt registered [ 5265.312420] Key type .llcrypt registered [ 5265.545327] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5265.552209] alg: No test for adler32 (adler32-zlib) [ 5266.436023] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 5266.543049] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 5268.135148] Key type lgssc registered [ 5268.605351] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5302.187540] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5303.487585] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5311.131839] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5337.231866] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 15:32:04 (1758742324) [ 5347.886388] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5349.356683] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5356.375298] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5366.770285] Lustre: Mounted lustre-client [ 5403.979129] LustreError: 174656:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af44442b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5403.989618] LustreError: 174656:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5404.013074] Lustre: Unmounted lustre-client [ 5421.100761] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5421.295617] Lustre: Mounted lustre-client [ 5453.666372] LustreError: 175316:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af448b19800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5453.676890] LustreError: 175316:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5453.679163] LustreError: 175316:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5453.703176] Lustre: Unmounted lustre-client [ 5471.553081] Key type lgssc unregistered [ 5471.693341] LNet: 175723:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5471.695508] LNetError: 175723:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5471.703752] LNet: Removed LNI 192.168.204.40@tcp [ 5472.002573] Key type .llcrypt unregistered [ 5472.003777] Key type ._llcrypt unregistered [ 5477.425973] Key type ._llcrypt registered [ 5477.427485] Key type .llcrypt registered [ 5477.922372] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 15:34:25 (1758742465) [ 5478.648887] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5478.663838] alg: No test for adler32 (adler32-zlib) [ 5479.526758] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 5479.616522] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 5481.207227] Key type lgssc registered [ 5481.626642] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5499.012974] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5500.170767] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5506.188633] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5516.786542] Lustre: Mounted lustre-client [ 5523.884324] LustreError: 178973:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af44921d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5523.891594] LustreError: 178973:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5523.914175] Lustre: Unmounted lustre-client [ 5549.535927] Key type lgssc unregistered [ 5549.671495] LNet: 179376:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5549.674515] LNetError: 179376:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5549.683286] LNet: Removed LNI 192.168.204.40@tcp [ 5549.958911] Key type .llcrypt unregistered [ 5549.959906] Key type ._llcrypt unregistered [ 5554.218866] Key type ._llcrypt registered [ 5554.219900] Key type .llcrypt registered [ 5554.375903] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5554.382609] alg: No test for adler32 (adler32-zlib) [ 5555.260720] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 5555.355085] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 5556.943180] Key type lgssc registered [ 5557.361948] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5574.553275] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5575.780058] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5581.830416] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5597.679875] Lustre: Mounted lustre-client [ 5600.493211] LustreError: 181938:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af444438000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5600.501217] LustreError: 181938:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5600.525153] Lustre: Unmounted lustre-client [ 5617.892804] Key type lgssc unregistered [ 5618.015470] LNet: 182341:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5618.017660] LNetError: 182341:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5618.028652] LNet: Removed LNI 192.168.204.40@tcp [ 5618.289057] Key type .llcrypt unregistered [ 5618.290131] Key type ._llcrypt unregistered [ 5623.606226] Key type ._llcrypt registered [ 5623.607363] Key type .llcrypt registered [ 5624.078176] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 15:36:51 (1758742611) [ 5624.790082] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5624.834235] alg: No test for adler32 (adler32-zlib) [ 5625.679451] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 5625.756912] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 5627.335224] Key type lgssc registered [ 5627.733263] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5644.295397] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5645.403918] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5651.222774] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5652.363758] Lustre: Mounted lustre-client [ 5658.713247] LustreError: 185590:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af4506e8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5658.721866] LustreError: 185590:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5658.743130] Lustre: Unmounted lustre-client [ 5683.958644] Key type lgssc unregistered [ 5684.076531] LNet: 185992:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5684.079305] LNetError: 185992:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5684.086574] LNet: Removed LNI 192.168.204.40@tcp [ 5684.308711] Key type .llcrypt unregistered [ 5684.310252] Key type ._llcrypt unregistered [ 5688.308909] Key type ._llcrypt registered [ 5688.313568] Key type .llcrypt registered [ 5688.470248] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5688.481090] alg: No test for adler32 (adler32-zlib) [ 5689.336934] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 5689.415479] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 5690.991190] Key type lgssc registered [ 5691.335745] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5707.903434] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5709.013214] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5714.665437] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5715.831334] Lustre: Mounted lustre-client [ 5718.483608] LustreError: 188552:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af442c4a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5718.490257] LustreError: 188552:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5718.511116] Lustre: Unmounted lustre-client [ 5735.670614] Key type lgssc unregistered [ 5735.803367] LNet: 188954:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5735.806917] LNetError: 188954:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5735.813660] LNet: Removed LNI 192.168.204.40@tcp [ 5736.052230] Key type .llcrypt unregistered [ 5736.053783] Key type ._llcrypt unregistered [ 5740.947471] Key type ._llcrypt registered [ 5740.948374] Key type .llcrypt registered [ 5741.403184] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 15:38:49 (1758742729) [ 5748.064389] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5748.073058] alg: No test for adler32 (adler32-zlib) [ 5748.930764] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 5749.022604] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 5750.607161] Key type lgssc registered [ 5751.012457] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5780.954953] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5782.174643] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5789.576591] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5810.887826] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 15:39:58 (1758742798) [ 5820.000527] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5821.109236] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5827.352739] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5842.925405] Lustre: Mounted lustre-client [ 5844.779957] LustreError: 195450:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af448b35800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5844.788823] LustreError: 195450:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5844.808582] Lustre: Unmounted lustre-client [ 5870.439222] Key type lgssc unregistered [ 5870.556660] LNet: 195852:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5870.559380] LNetError: 195852:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5870.567512] LNet: Removed LNI 192.168.204.40@tcp [ 5870.816230] Key type .llcrypt unregistered [ 5870.817160] Key type ._llcrypt unregistered [ 5876.090338] Key type ._llcrypt registered [ 5876.091717] Key type .llcrypt registered [ 5876.608169] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 5877.182743] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 15:41:04 (1758742864) [ 5883.269211] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5883.314303] alg: No test for adler32 (adler32-zlib) [ 5884.170030] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 5884.250635] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 5885.831196] Key type lgssc registered [ 5886.214072] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5918.569371] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5919.742978] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5927.098359] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid [ 5933.441591] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid [ 5934.595454] Lustre: Mounted lustre-client [ 5940.501566] LustreError: 201372:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af445284000: lov tgt 1000 not cleaned! deathrow=0, lovrc=1 [ 5940.509164] LustreError: 201372:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5940.535721] Lustre: Unmounted lustre-client [ 6003.036947] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6004.222316] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6011.402900] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6032.994475] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 15:43:40 (1758743020) [ 6033.484174] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 6034.100836] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 15:43:41 (1758743021) [ 6051.028361] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6052.209919] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6074.273394] Key type lgssc unregistered [ 6074.390370] LNet: 207530:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6074.392964] LNetError: 207530:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6074.400715] LNet: Removed LNI 192.168.204.40@tcp [ 6074.654566] Key type .llcrypt unregistered [ 6074.655688] Key type ._llcrypt unregistered [ 6079.803419] Key type ._llcrypt registered [ 6079.805019] Key type .llcrypt registered [ 6080.257640] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 15:44:28 (1758743068) [ 6092.226510] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6092.273668] alg: No test for adler32 (adler32-zlib) [ 6093.143485] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 6093.227613] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 6094.815103] Key type lgssc registered [ 6095.249620] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6110.139619] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6111.410567] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6117.891037] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6129.282420] Key type lgssc unregistered [ 6129.418310] LNet: 211151:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6129.421403] LNetError: 211151:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6129.433362] LNet: Removed LNI 192.168.204.40@tcp [ 6129.689578] Key type .llcrypt unregistered [ 6129.690841] Key type ._llcrypt unregistered [ 6135.261734] Key type ._llcrypt registered [ 6135.262720] Key type .llcrypt registered [ 6135.760745] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 15:45:23 (1758743123) [ 6136.792718] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6136.807917] alg: No test for adler32 (adler32-zlib) [ 6137.669600] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 6137.770269] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 6139.359193] Key type lgssc registered [ 6139.756934] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6157.678992] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6158.972693] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6165.350698] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6180.848109] Lustre: Mounted lustre-client [ 6185.953518] Lustre: lustre-MDT0000-mdc-ffff8af446efd800: Connection to lustre-MDT0000 (at 192.168.204.140@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6191.074964] Lustre: lustre-MDT0001-mdc-ffff8af446efd800: Connection to lustre-MDT0001 (at 192.168.204.140@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6206.432970] LustreError: MGC192.168.204.140@tcp: Connection to MGS (at 192.168.204.140@tcp) was lost; in progress operations using this service will fail [ 6206.438898] Lustre: Evicted from MGS (at 192.168.204.140@tcp) after server handle changed from 0x928c1a2e591ec49d to 0x928c1a2e591eddfd [ 6206.442211] Lustre: MGC192.168.204.140@tcp: Connection restored to 192.168.204.140@tcp (at 192.168.204.140@tcp) [ 6223.138395] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6225.890870] LustreError: lustre-MDT0000-mdc-ffff8af446efd800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 6225.908126] Lustre: lustre-MDT0000-mdc-ffff8af446efd800: Connection restored to 192.168.204.140@tcp (at 192.168.204.140@tcp) [ 6226.791317] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 3 sec [ 6228.051807] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6228.598171] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6229.261456] LustreError: 215570:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af446efd800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6229.265635] LustreError: 215570:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6229.285143] Lustre: Unmounted lustre-client [ 6263.426970] Key type lgssc unregistered [ 6263.554473] LNet: 215975:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6263.556750] LNetError: 215975:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6263.563855] LNet: Removed LNI 192.168.204.40@tcp [ 6263.856085] Key type .llcrypt unregistered [ 6263.857297] Key type ._llcrypt unregistered [ 6269.302481] Key type ._llcrypt registered [ 6269.303867] Key type .llcrypt registered [ 6269.818958] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 15:47:37 (1758743257) [ 6270.711983] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6270.737177] alg: No test for adler32 (adler32-zlib) [ 6271.597058] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 6271.684840] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 6273.271144] Key type lgssc registered [ 6273.711525] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6292.118446] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6293.393994] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6314.239268] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6315.374248] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6324.364280] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6330.340437] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6364.028927] Key type lgssc unregistered [ 6364.156455] LNet: 221082:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6364.159914] LNetError: 221082:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6364.170349] LNet: Removed LNI 192.168.204.40@tcp [ 6364.439480] Key type .llcrypt unregistered [ 6364.440509] Key type ._llcrypt unregistered [ 6379.862801] Key type ._llcrypt registered [ 6379.864262] Key type .llcrypt registered [ 6380.119954] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6380.133617] alg: No test for adler32 (adler32-zlib) [ 6381.004955] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 6381.104051] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 6382.695165] Key type lgssc registered [ 6383.134151] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6411.981677] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6413.229689] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6420.720429] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6446.831628] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 15:50:34 (1758743434) [ 6485.386876] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6486.646071] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6494.359435] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6516.004193] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 15:51:43 (1758743503) [ 6518.566324] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 15:51:46 (1758743506) [ 6529.204697] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6530.459293] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6536.683478] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6547.440881] Lustre: Mounted lustre-client [ 6550.595066] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [ 6551.340831] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 6551.919268] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 6552.500271] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [ 6557.666758] Lustre: lustre-MDT0000-mdc-ffff8af444415000: Connection to lustre-MDT0000 (at 192.168.204.140@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6567.906055] LustreError: MGC192.168.204.140@tcp: Connection to MGS (at 192.168.204.140@tcp) was lost; in progress operations using this service will fail [ 6567.915066] Lustre: Evicted from MGS (at 192.168.204.140@tcp) after server handle changed from 0xc6d28cc7df28a728 to 0xc6d28cc7df28a9ac [ 6567.918368] Lustre: MGC192.168.204.140@tcp: Connection restored to 192.168.204.140@tcp (at 192.168.204.140@tcp) [ 6571.901861] Lustre: lustre-MDT0000-mdc-ffff8af444415000: Connection restored to 192.168.204.140@tcp (at 192.168.204.140@tcp) [ 6573.131563] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6573.730848] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6575.624819] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [ 6578.146091] Lustre: lustre-MDT0000-mdc-ffff8af444415000: Connection to lustre-MDT0000 (at 192.168.204.140@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6592.991085] Lustre: 232233:0:(client.c:2464:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1758743565/real 1758743565] req@00000000ad223ff1 x1844176086907136/t0(0) o101->MGC192.168.204.140@tcp@192.168.204.140@tcp:26/25 lens 328/344 e 0 to 1 dl 1758743581 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 6592.998320] LustreError: MGC192.168.204.140@tcp: Connection to MGS (at 192.168.204.140@tcp) was lost; in progress operations using this service will fail [ 6593.002571] Lustre: 232233:0:(mgc_request.c:1900:mgc_process_log()) MGC192.168.204.140@tcp: IR log lustre-cliir failed, not fatal: rc = -5 [ 6593.007479] Lustre: Evicted from MGS (at 192.168.204.140@tcp) after server handle changed from 0xc6d28cc7df28a9ac to 0xc6d28cc7df28ac92 [ 6593.010625] Lustre: MGC192.168.204.140@tcp: Connection restored to 192.168.204.140@tcp (at 192.168.204.140@tcp) [ 6596.141785] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6596.706688] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6598.283360] LustreError: 234672:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af444415000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6598.289909] LustreError: 234672:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6598.307794] Lustre: Unmounted lustre-client [ 6631.437641] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6632.629738] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6638.744297] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6654.451745] Lustre: Mounted lustre-client [ 6654.852958] LustreError: 236288:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af445280800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6654.860235] LustreError: 236288:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6654.862550] LustreError: 236288:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 6654.885765] Lustre: Unmounted lustre-client [ 6672.785159] Key type lgssc unregistered [ 6672.901506] LNet: 236690:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6672.903794] LNetError: 236690:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6672.910914] LNet: Removed LNI 192.168.204.40@tcp [ 6673.209921] Key type .llcrypt unregistered [ 6673.211293] Key type ._llcrypt unregistered [ 6678.603152] Key type ._llcrypt registered [ 6678.604167] Key type .llcrypt registered [ 6679.046880] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 15:54:26 (1758743666) [ 6680.350390] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6680.359946] alg: No test for adler32 (adler32-zlib) [ 6681.205546] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 6681.283457] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 6682.871227] Key type lgssc registered [ 6683.263289] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6710.619338] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6713.483467] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6730.191672] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6742.020889] Lustre: Mounted lustre-client [ 6745.526174] LustreError: 239861:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af44399f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6745.540318] LustreError: 239861:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6745.583893] Lustre: Unmounted lustre-client [ 6803.112574] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6805.822635] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6817.108384] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6833.174749] Lustre: Mounted lustre-client [ 6840.075958] LustreError: 241460:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af447537000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6840.091377] LustreError: 241460:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6840.095602] LustreError: 241460:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 6840.145144] Lustre: Unmounted lustre-client [ 6886.173337] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6888.549867] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6901.256987] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6901.806968] Lustre: Mounted lustre-client [ 6902.930740] LustreError: 243077:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af448b8d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6902.954544] LustreError: 243077:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6902.960580] LustreError: 243077:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 6903.032591] Lustre: Unmounted lustre-client [ 6935.203637] Key type lgssc unregistered [ 6935.401906] LNet: 243481:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6935.413165] LNetError: 243481:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6935.425829] LNet: Removed LNI 192.168.204.40@tcp [ 6935.893906] Key type .llcrypt unregistered [ 6935.895766] Key type ._llcrypt unregistered [ 6946.271238] Key type ._llcrypt registered [ 6946.273666] Key type .llcrypt registered [ 6947.288806] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 15:58:54 (1758743934) [ 6949.396962] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6949.423358] alg: No test for adler32 (adler32-zlib) [ 6950.387591] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 6950.527861] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 6952.159214] Key type lgssc registered [ 6953.157377] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6981.135628] Key type lgssc unregistered [ 6981.331204] LNet: 245794:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6981.337916] LNetError: 245794:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6981.363232] LNet: Removed LNI 192.168.204.40@tcp [ 6981.829459] Key type .llcrypt unregistered [ 6981.830986] Key type ._llcrypt unregistered [ 6998.843944] Key type ._llcrypt registered [ 6998.845516] Key type .llcrypt registered [ 6999.162432] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6999.172708] alg: No test for adler32 (adler32-zlib) [ 7000.136732] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 7000.313284] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 7001.959242] Key type lgssc registered [ 7002.592344] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7044.923828] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7046.804876] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7058.163625] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7087.599260] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [ 7088.616736] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 16:01:15 (1758744075) [ 7109.679734] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7112.279566] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7125.273995] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7139.042961] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 7140.488295] Lustre: Mounted lustre-client [ 7145.960085] Lustre: lustre-OST0001-osc-ffff8af447536800: Connection to lustre-OST0001 (at 192.168.204.140@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7150.324309] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7160.618745] LustreError: 252292:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 7160.655360] Lustre: Unmounted lustre-client [ 7188.712407] Key type lgssc unregistered [ 7188.895472] LNet: 252695:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7188.900569] LNetError: 252695:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7188.913950] LNet: Removed LNI 192.168.204.40@tcp [ 7189.284824] Key type .llcrypt unregistered [ 7189.286566] Key type ._llcrypt unregistered [ 7215.080972] Key type ._llcrypt registered [ 7215.083704] Key type .llcrypt registered [ 7215.365398] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7215.373567] alg: No test for adler32 (adler32-zlib) [ 7216.351822] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 7216.522823] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 7218.159179] Key type lgssc registered [ 7218.944397] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7265.791922] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7267.542478] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7278.628945] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7305.335673] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 16:04:52 (1758744292) [ 7327.025180] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7329.015352] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7342.442823] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 16:05:30 (1758744330) [ 7357.407681] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7359.275543] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7367.998680] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7374.852890] Lustre: Mounted lustre-client [ 7384.502050] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 7391.377184] LustreError: 260521:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7391.419780] Lustre: Unmounted lustre-client [ 7463.476182] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7465.992374] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7505.033328] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7510.538638] Lustre: Mounted lustre-client [ 7511.534985] LustreError: 262586:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af448b8e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7511.547673] LustreError: 262586:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7511.550743] LustreError: 262586:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 7511.602943] Lustre: Unmounted lustre-client [ 7531.537504] Key type lgssc unregistered [ 7531.681266] LNet: 262988:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7531.685382] LNetError: 262988:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7531.697260] LNet: Removed LNI 192.168.204.40@tcp [ 7532.019035] Key type .llcrypt unregistered [ 7532.020935] Key type ._llcrypt unregistered [ 7544.974840] Key type ._llcrypt registered [ 7544.977107] Key type .llcrypt registered [ 7545.166073] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7545.177801] alg: No test for adler32 (adler32-zlib) [ 7546.085806] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 7546.184823] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 7547.775195] Key type lgssc registered [ 7548.311893] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7586.271197] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7587.946807] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7598.151602] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7624.472892] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [ 7625.302681] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 16:10:12 (1758744612) [ 7639.919362] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7642.159569] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7651.372743] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7659.502257] Lustre: Mounted lustre-client [ 7665.916728] LustreError: 269029:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af444b98000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7665.932459] LustreError: 269029:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7665.964406] Lustre: Unmounted lustre-client [ 7693.665635] Key type lgssc unregistered [ 7693.818293] LNet: 269432:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7693.826645] LNetError: 269432:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7693.839432] LNet: Removed LNI 192.168.204.40@tcp [ 7694.155908] Key type .llcrypt unregistered [ 7694.159459] Key type ._llcrypt unregistered [ 7700.985271] Key type ._llcrypt registered [ 7700.987260] Key type .llcrypt registered [ 7701.598649] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [ 7702.425342] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 16:11:30 (1758744690) [ 7707.221750] Key type .llcrypt unregistered [ 7707.223583] Key type ._llcrypt unregistered [ 7710.859088] Key type ._llcrypt registered [ 7710.860854] Key type .llcrypt registered [ 7711.040193] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7711.048188] alg: No test for adler32 (adler32-zlib) [ 7711.961129] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 7712.093678] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 7713.703155] Key type lgssc registered [ 7714.381919] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7739.786528] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7754.268136] Lustre: Mounted lustre-client [ 7755.349124] LustreError: 272439:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af442c49800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7755.367488] LustreError: 272439:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7755.405914] Lustre: Unmounted lustre-client [ 7775.002537] Key type lgssc unregistered [ 7775.147763] LNet: 272841:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7775.152739] LNetError: 272841:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7775.164760] LNet: Removed LNI 192.168.204.40@tcp [ 7775.462064] Key type .llcrypt unregistered [ 7775.463870] Key type ._llcrypt unregistered [ 7781.983057] Key type ._llcrypt registered [ 7781.984539] Key type .llcrypt registered [ 7782.646505] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 16:12:50 (1758744770) [ 7783.786702] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7783.795883] alg: No test for adler32 (adler32-zlib) [ 7784.710691] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 7784.833497] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 7786.447135] Key type lgssc registered [ 7786.968665] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7856.742585] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7870.087939] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7871.661622] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7872.908166] Lustre: Mounted lustre-client [ 7873.759408] LustreError: 275881:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af447228000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7873.767424] LustreError: 275881:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7873.793563] Lustre: Unmounted lustre-client [ 7893.626983] Key type lgssc unregistered [ 7893.766840] LNet: 276283:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7893.772772] LNetError: 276283:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7893.780852] LNet: Removed LNI 192.168.204.40@tcp [ 7894.197386] Key type .llcrypt unregistered [ 7894.200251] Key type ._llcrypt unregistered [ 7902.641275] Key type ._llcrypt registered [ 7902.642986] Key type .llcrypt registered [ 7903.331900] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 16:14:50 (1758744890) [ 7904.377043] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7904.418119] alg: No test for adler32 (adler32-zlib) [ 7905.340830] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 7905.463800] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 7907.063271] Key type lgssc registered [ 7907.642560] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7933.331786] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7935.118270] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7944.383566] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7951.861854] Lustre: Mounted lustre-client [ 7956.966534] Lustre: lustre-MDT0000-mdc-ffff8af448a05800: Connection to lustre-MDT0000 (at 192.168.204.140@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7960.325412] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7960.341846] LustreError: 279346:0:(file.c:6101:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [ 7960.541167] LustreError: 279365:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af448a05800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7960.550121] LustreError: MGC192.168.204.140@tcp: Connection to MGS (at 192.168.204.140@tcp) was lost; in progress operations using this service will fail [ 7960.552863] LustreError: 279365:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7960.587488] Lustre: Unmounted lustre-client [ 7975.445280] Key type lgssc unregistered [ 7975.604657] LNet: 279760:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7975.607821] LNetError: 279760:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7975.620894] LNet: Removed LNI 192.168.204.40@tcp [ 7976.036616] Key type .llcrypt unregistered [ 7976.038227] Key type ._llcrypt unregistered [ 7984.553265] Key type ._llcrypt registered [ 7984.554707] Key type .llcrypt registered [ 7985.230779] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 16:16:12 (1758744972) [ 7986.430018] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7986.442833] alg: No test for adler32 (adler32-zlib) [ 7987.402455] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 7987.532388] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 7989.167430] Key type lgssc registered [ 7989.741519] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8011.873751] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8013.485346] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8021.685942] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8028.161247] Lustre: Mounted lustre-client [ 8033.253084] Lustre: lustre-MDT0001-mdc-ffff8af448966000: Connection to lustre-MDT0001 (at 192.168.204.140@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8044.615751] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 8045.389099] LustreError: 282853:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af448966000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8045.399975] LustreError: 282853:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8045.452907] Lustre: Unmounted lustre-client [ 8058.253442] Key type lgssc unregistered [ 8058.443076] LNet: 283247:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8058.449500] LNetError: 283247:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8058.466710] LNet: Removed LNI 192.168.204.40@tcp [ 8058.774142] Key type .llcrypt unregistered [ 8058.776052] Key type ._llcrypt unregistered [ 8065.395252] Key type ._llcrypt registered [ 8065.396671] Key type .llcrypt registered [ 8066.086809] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 16:17:33 (1758745053) [ 8073.886662] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8073.910334] alg: No test for adler32 (adler32-zlib) [ 8074.806779] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 8074.909262] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 8076.511315] Key type lgssc registered [ 8076.982616] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8122.886680] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8124.087074] Lustre: Mounted lustre-client [ 8143.844056] Lustre: lustre-MDT0001-mdc-ffff8af448a06800: Connection to lustre-MDT0001 (at 192.168.204.140@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8146.899838] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 8157.159652] LustreError: 288587:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 8157.175278] Lustre: Unmounted lustre-client [ 8175.540249] Key type lgssc unregistered [ 8175.723752] LNet: 288987:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8175.729147] LNetError: 288987:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8175.742262] LNet: Removed LNI 192.168.204.40@tcp [ 8176.084638] Key type .llcrypt unregistered [ 8176.086422] Key type ._llcrypt unregistered [ 8183.773180] Key type ._llcrypt registered [ 8183.775707] Key type .llcrypt registered [ 8184.461819] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 16:19:31 (1758745171) [ 8185.186300] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [ 8185.850080] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 16:19:33 (1758745173) [ 8186.518151] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [ 8187.221472] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 16:19:34 (1758745174) [ 8187.825363] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [ 8188.476523] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 16:19:36 (1758745176) [ 8189.116159] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [ 8189.796671] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 16:19:37 (1758745177) [ 8190.541461] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [ 8191.291502] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 16:19:38 (1758745178) [ 8195.045274] Key type .llcrypt unregistered [ 8195.046907] Key type ._llcrypt unregistered [ 8197.914798] Key type ._llcrypt registered [ 8197.916782] Key type .llcrypt registered [ 8198.106747] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8198.115382] alg: No test for adler32 (adler32-zlib) [ 8199.003701] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 8199.105502] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 8200.719185] Key type lgssc registered [ 8201.252234] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8231.856650] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8233.216815] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8241.855933] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8247.284751] Lustre: Mounted lustre-client [ 8247.796790] LustreError: 294563:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af44722d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8247.806206] LustreError: 294563:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8247.833462] Lustre: Unmounted lustre-client [ 8270.643827] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 16:20:58 (1758745258) [ 8281.374048] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8282.661576] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8289.932412] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8300.535811] Lustre: Mounted lustre-client [ 8301.044980] LustreError: 296675:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af448262000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8301.054552] LustreError: 296675:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8301.057467] LustreError: 296675:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 8301.084181] Lustre: Unmounted lustre-client [ 8321.081694] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 16:21:48 (1758745308) [ 8351.475297] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8352.818659] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8359.827305] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8400.642122] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 16:23:08 (1758745388) [ 8404.974631] Key type lgssc unregistered [ 8405.103773] LNet: 302114:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8405.107203] LNetError: 302114:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8422.501787] LNet: Removed LNI 192.168.204.40@tcp [ 8422.756844] Key type .llcrypt unregistered [ 8422.758356] Key type ._llcrypt unregistered [ 8550.967896] Key type ._llcrypt registered [ 8550.969468] Key type .llcrypt registered [ 8551.151438] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8551.161435] alg: No test for adler32 (adler32-zlib) [ 8552.032555] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 8552.122462] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 8553.711160] Key type lgssc registered [ 8554.135509] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8562.686090] Lustre: DEBUG MARKER: SKIP: conf-sanity test_73c unable to configure net [ 8567.403852] Key type lgssc unregistered [ 8567.531341] LNet: 303795:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8567.534363] LNetError: 303795:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8567.542349] LNet: Removed LNI 192.168.204.40@tcp [ 8567.822668] Key type .llcrypt unregistered [ 8567.823948] Key type ._llcrypt unregistered [ 8571.924824] Key type ._llcrypt registered [ 8571.926382] Key type .llcrypt registered [ 8572.399946] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 16:26:00 (1758745560) [ 8579.465214] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8579.512282] alg: No test for adler32 (adler32-zlib) [ 8580.378849] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 8580.465783] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 8582.055243] Key type lgssc registered [ 8582.505481] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8601.376744] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 16:26:29 (1758745589) [ 8605.795058] Key type lgssc unregistered [ 8605.913404] LNet: 307336:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8605.915571] LNetError: 307336:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8605.925419] LNet: Removed LNI 192.168.204.40@tcp [ 8606.175650] Key type .llcrypt unregistered [ 8606.177062] Key type ._llcrypt unregistered [ 8610.069547] Key type ._llcrypt registered [ 8610.070989] Key type .llcrypt registered [ 8610.246413] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8610.253900] alg: No test for adler32 (adler32-zlib) [ 8611.112625] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 8611.192259] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 8612.775176] Key type lgssc registered [ 8613.190400] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8634.210244] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8635.415793] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8643.010050] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8647.189651] LNet: Added LNI 192.168.204.40@tcp42 [8/256/0/180] [ 8648.067415] LNet: Removed LNI 192.168.204.40@tcp [ 8649.519280] Lustre: 310224:0:(ldlm_lib.c:532:client_obd_setup()) lustre-MDT0000-mdc-ffff8af444b9f800: config has no valid NIDs, force dynamic NIDs [ 8650.560983] Lustre: Mounted lustre-client [ 8650.581204] LustreError: 310240:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af444b9f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8650.588295] LustreError: 310240:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8650.611157] Lustre: Unmounted lustre-client [ 8670.130880] Key type lgssc unregistered [ 8670.293741] LNet: 311078:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8670.296838] LNetError: 311078:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8670.310786] LNet: Removed LNI 192.168.204.40@tcp42 [ 8670.520989] Key type .llcrypt unregistered [ 8670.522413] Key type ._llcrypt unregistered [ 8674.502427] Key type ._llcrypt registered [ 8674.503595] Key type .llcrypt registered [ 8675.014463] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 16:27:42 (1758745662) [ 8675.798757] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8675.829621] alg: No test for adler32 (adler32-zlib) [ 8676.688550] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 8676.781898] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 8678.367243] Key type lgssc registered [ 8678.808710] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8697.692411] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8698.967248] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8705.141564] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8715.757905] Lustre: Mounted lustre-client [ 8736.218351] Lustre: Mounted fs15246-client [ 8740.888693] LustreError: 315197:0:(lov_obd.c:784:lov_cleanup()) fs15246-clilov-ffff8af447240000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8740.896614] LustreError: 315197:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 8740.911160] Lustre: Unmounted fs15246-client [ 8753.587214] LustreError: 315416:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8af44b250800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8753.592154] LustreError: 315416:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8753.593892] LustreError: 315416:0:(obd_class.h:479:obd_check_dev()) Skipped 3 previous similar messages [ 8753.614175] Lustre: Unmounted lustre-client [ 8770.696139] Key type lgssc unregistered [ 8770.813541] LNet: 315818:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8770.816390] LNetError: 315818:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8770.824492] LNet: Removed LNI 192.168.204.40@tcp [ 8771.055239] Key type .llcrypt unregistered [ 8771.056566] Key type ._llcrypt unregistered [ 8774.831202] Key type ._llcrypt registered [ 8774.832586] Key type .llcrypt registered [ 8775.342772] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 16:29:23 (1758745763) [ 8775.859238] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8775.885042] alg: No test for adler32 (adler32-zlib) [ 8776.734654] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 8776.818442] LNet: Added LNI 192.168.204.40@tcp [8/256/0/180] [ 8778.407152] Key type lgssc registered [ 8778.787769] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8821.736484] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8822.895178] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8830.091703] Lustre: DEBUG MARKER: oleg440-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8871.414286] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 8618 sec ========== 16:30:59 (1758745859) [ 8871.971143] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 16:30:59 (1758745859) === [ 8873.119283] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 16:31:00 (1758745860) === [ 8884.195094] Key type lgssc unregistered [ 8884.308441] LNet: 324950:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8884.310894] LNetError: 324950:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8884.319634] LNet: Removed LNI 192.168.204.40@tcp [ 8884.545387] Key type .llcrypt unregistered [ 8884.546416] Key type ._llcrypt unregistered