[ 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 465992782 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.001009] APIC: Switch to symmetric I/O mode setup [ 0.002319] x2apic enabled [ 0.003006] Switched APIC routing to physical x2apic. [ 0.004015] kvm-guest: setup PV IPIs [ 0.007437] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008021] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009012] pid_max: default: 32768 minimum: 301 [ 0.010123] LSM: Security Framework initializing [ 0.011048] Yama: becoming mindful. [ 0.012029] SELinux: Initializing. [ 0.013077] *** VALIDATE selinux *** [ 0.021163] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026413] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.028036] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029126] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031102] *** VALIDATE tmpfs *** [ 0.033039] *** VALIDATE proc *** [ 0.034257] *** VALIDATE cgroup *** [ 0.035010] *** VALIDATE cgroup2 *** [ 0.036292] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.038164] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.039009] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.040029] Spectre V2 : User space: Vulnerable [ 0.041010] Speculative Store Bypass: Vulnerable [ 0.044076] debug: unmapping init [mem 0xffffffffbd859000-0xffffffffbd860fff] [ 0.046172] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.047699] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.048023] ... version: 2 [ 0.049015] ... bit width: 48 [ 0.050011] ... generic registers: 4 [ 0.051014] ... value mask: 0000ffffffffffff [ 0.052014] ... max period: 00007fffffffffff [ 0.053017] ... fixed-purpose events: 3 [ 0.054012] ... event mask: 000000070000000f [ 0.055302] rcu: Hierarchical SRCU implementation. [ 0.057491] smp: Bringing up secondary CPUs ... [ 0.058590] x86: Booting SMP configuration: [ 0.059026] .... node #0, CPUs: #1 #2 #3 [ 0.062348] smp: Brought up 1 node, 4 CPUs [ 0.064013] smpboot: Max logical packages: 1 [ 0.065013] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.154023] node 0 deferred pages initialised in 86ms [ 0.157178] devtmpfs: initialized [ 0.158223] x86/mm: Memory block size: 128MB [ 0.161746] gcov: version magic: 0x41383552 [ 0.163047] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.164081] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.165242] pinctrl core: initialized pinctrl subsystem [ 0.166147] [ 0.166475] ************************************************************* [ 0.167016] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.168015] ** ** [ 0.169015] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.170014] ** ** [ 0.171014] ** This means that this kernel is built to expose internal ** [ 0.172016] ** IOMMU data structures, which may compromise security on ** [ 0.173015] ** your system. ** [ 0.174014] ** ** [ 0.175013] ** If you see this message and you are not debugging the ** [ 0.176015] ** kernel, report this immediately to your vendor! ** [ 0.177015] ** ** [ 0.178015] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.179013] ************************************************************* [ 0.180581] NET: Registered protocol family 16 [ 0.181459] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.182058] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.183062] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.184392] cpuidle: using governor menu [ 0.186802] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.187506] PCI: Using configuration type 1 for base access [ 0.188127] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.197159] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.199038] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.203168] cryptd: max_cpu_qlen set to 1000 [ 0.207269] ACPI: Added _OSI(Module Device) [ 0.208014] ACPI: Added _OSI(Processor Device) [ 0.210014] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.211014] ACPI: Added _OSI(Processor Aggregator Device) [ 0.215429] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.219508] ACPI: Interpreter enabled [ 0.221060] ACPI: PM: (supports S0 S3 S4 S5) [ 0.223016] ACPI: Using IOAPIC for interrupt routing [ 0.224132] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.227367] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.238243] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.240036] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.243020] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.246078] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.251359] acpiphp: Slot [2] registered [ 0.252085] acpiphp: Slot [3] registered [ 0.253080] acpiphp: Slot [4] registered [ 0.255122] acpiphp: Slot [5] registered [ 0.256125] acpiphp: Slot [6] registered [ 0.258113] acpiphp: Slot [7] registered [ 0.259081] acpiphp: Slot [8] registered [ 0.260085] acpiphp: Slot [9] registered [ 0.262103] acpiphp: Slot [10] registered [ 0.263125] acpiphp: Slot [11] registered [ 0.265118] acpiphp: Slot [12] registered [ 0.266103] acpiphp: Slot [13] registered [ 0.268110] acpiphp: Slot [14] registered [ 0.270088] acpiphp: Slot [15] registered [ 0.271091] acpiphp: Slot [16] registered [ 0.273107] acpiphp: Slot [17] registered [ 0.274095] acpiphp: Slot [18] registered [ 0.276099] acpiphp: Slot [19] registered [ 0.277098] acpiphp: Slot [20] registered [ 0.279091] acpiphp: Slot [21] registered [ 0.280106] acpiphp: Slot [22] registered [ 0.282099] acpiphp: Slot [23] registered [ 0.283113] acpiphp: Slot [24] registered [ 0.284097] acpiphp: Slot [25] registered [ 0.286077] acpiphp: Slot [26] registered [ 0.287119] acpiphp: Slot [27] registered [ 0.289113] acpiphp: Slot [28] registered [ 0.291102] acpiphp: Slot [29] registered [ 0.292123] acpiphp: Slot [30] registered [ 0.293089] acpiphp: Slot [31] registered [ 0.295085] PCI host bridge to bus 0000:00 [ 0.297019] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.299020] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.301029] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.304024] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.306018] pci_bus 0000:00: root bus resource [mem 0x180000000-0x1ffffffff window] [ 0.310031] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.312223] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.317401] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.321506] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.328564] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.333066] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.335018] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.338019] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.340028] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.342314] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.345782] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.348043] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.350684] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.355015] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.366726] pci 0000:00:02.0: reg 0x20: [mem 0xfebf4000-0xfebf7fff 64bit pref] [ 0.370015] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.375448] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.381016] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.386015] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.398017] pci 0000:00:05.0: reg 0x20: [mem 0xfebf8000-0xfebfbfff 64bit pref] [ 0.404580] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.409013] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.413013] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.421014] pci 0000:00:06.0: reg 0x20: [mem 0xfebfc000-0xfebfffff 64bit pref] [ 0.429000] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.432404] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.434404] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.437376] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.439222] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.443176] iommu: Default domain type: Passthrough [ 0.445475] SCSI subsystem initialized [ 0.446111] ACPI: bus type USB registered [ 0.448117] usbcore: registered new interface driver usbfs [ 0.450064] usbcore: registered new interface driver hub [ 0.451055] usbcore: registered new device driver usb [ 0.453213] pps_core: LinuxPPS API ver. 1 registered [ 0.455013] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.458061] PTP clock support registered [ 0.460170] EDAC MC: Ver: 3.0.0 [ 0.462445] PCI: Using ACPI for IRQ routing [ 0.464754] NetLabel: Initializing [ 0.466011] NetLabel: domain hash size = 128 [ 0.468012] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.470097] NetLabel: unlabeled traffic allowed by default [ 0.472123] vgaarb: loaded [ 0.473363] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.475013] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.479370] clocksource: Switched to clocksource kvm-clock [ 0.585923] VFS: Disk quotas dquot_6.6.0 [ 0.587562] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.590650] *** VALIDATE ramfs *** [ 0.592060] *** VALIDATE hugetlbfs *** [ 0.593858] pnp: PnP ACPI init [ 0.596427] pnp: PnP ACPI: found 6 devices [ 0.616835] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.619909] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.622328] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.624595] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.626725] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.629018] pci_bus 0000:00: resource 8 [mem 0x180000000-0x1ffffffff window] [ 0.631408] NET: Registered protocol family 2 [ 0.634203] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.640671] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.645320] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.651431] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.655460] TCP: Hash tables configured (established 65536 bind 65536) [ 0.659718] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.663389] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.666744] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.670186] NET: Registered protocol family 1 [ 0.673040] RPC: Registered named UNIX socket transport module. [ 0.675387] RPC: Registered udp transport module. [ 0.677298] RPC: Registered tcp transport module. [ 0.679127] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.681353] NET: Registered protocol family 44 [ 0.683626] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.686209] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.688559] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.690885] PCI: CLS 0 bytes, default 64 [ 0.692833] Unpacking initramfs... [ 2.085377] debug: unmapping init [mem 0xffff8e2abcc64000-0xffff8e2abffcffff] [ 2.090804] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.094088] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.097820] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.598982] Initialise system trusted keyrings [ 2.600772] Key type blacklist registered [ 2.602699] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.611941] zbud: loaded [ 2.617292] *** VALIDATE nfs *** [ 2.618367] *** VALIDATE nfs4 *** [ 2.619778] pstore: using deflate compression [ 2.624313] Platform Keyring initialized [ 2.714709] NET: Registered protocol family 38 [ 2.716659] Key type asymmetric registered [ 2.718592] Asymmetric key parser 'x509' registered [ 2.720317] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.724208] io scheduler mq-deadline registered [ 2.725953] io scheduler kyber registered [ 2.727338] io scheduler bfq registered [ 2.729125] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.732177] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.735453] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.738579] ACPI: Power Button [PWRF] [ 2.828667] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.922794] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.018035] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.052718] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.083059] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.088269] Non-volatile memory driver v1.3 [ 3.089953] Linux agpgart interface v0.103 [ 3.120462] virtio_blk virtio1: [vda] 133832 512-byte logical blocks (68.5 MB/65.3 MiB) [ 3.123355] vda: detected capacity change from 0 to 68521984 [ 3.137683] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.140741] vdb: detected capacity change from 0 to 1073741824 [ 3.148148] libphy: Fixed MDIO Bus: probed [ 3.153873] usbcore: registered new interface driver usbserial_generic [ 3.156286] usbserial: USB Serial support registered for generic [ 3.158620] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.162966] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.164842] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.167460] mousedev: PS/2 mouse device common for all mice [ 3.170396] rtc_cmos 00:05: RTC can wake from S4 [ 3.173581] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.174533] rtc_cmos 00:05: registered as rtc0 [ 3.178571] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.180028] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.180800] intel_pstate: CPU model not supported [ 3.185406] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.189622] hid: raw HID events driver (C) Jiri Kosina [ 3.191679] usbcore: registered new interface driver usbhid [ 3.193662] usbhid: USB HID core driver [ 3.195190] drop_monitor: Initializing network drop monitor service [ 3.197535] Initializing XFRM netlink socket [ 3.199467] NET: Registered protocol family 10 [ 3.201886] Segment Routing with IPv6 [ 3.203101] NET: Registered protocol family 17 [ 3.205105] mpls_gso: MPLS GSO support [ 3.210437] RAS: Correctable Errors collector initialized. [ 3.212368] AVX version of gcm_enc/dec engaged. [ 3.214101] AES CTR mode by8 optimization enabled [ 3.297392] sched_clock: Marking stable (3297370663, 0)->(4218143017, -920772354) [ 3.302710] registered taskstats version 1 [ 3.304386] Loading compiled-in X.509 certificates [ 3.306334] zswap: loaded using pool lzo/zbud [ 3.343162] Key type big_key registered [ 3.360286] Key type encrypted registered [ 3.361871] ima: No TPM chip found, activating TPM-bypass! [ 3.364170] ima: Allocated hash algorithm: sha1 [ 3.365906] ima: No architecture policies found [ 3.367902] evm: Initialising EVM extended attributes: [ 3.370114] evm: security.selinux [ 3.371571] evm: security.ima [ 3.372725] evm: security.capability [ 3.374132] evm: HMAC attrs: 0x1 [ 3.377159] rtc_cmos 00:05: setting system clock to 2025-11-16 22:43:14 UTC (1763332994) [ 3.383593] debug: unmapping init [mem 0xffffffffbe803000-0xffffffffbe9fffff] [ 3.386558] debug: unmapping init [mem 0xffffffffbd582000-0xffffffffbd858fff] [ 3.393211] Write protecting the kernel read-only data: 28672k [ 3.396417] debug: unmapping init [mem 0xffffffffbbc03000-0xffffffffbbdfffff] [ 3.398912] debug: unmapping init [mem 0xffffffffbc514000-0xffffffffbc5fffff] [ 3.426992] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 3.434330] systemd[1]: Detected virtualization kvm. [ 3.436566] systemd[1]: Detected architecture x86-64. [ 3.438580] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.466532] systemd[1]: No hostname configured. [ 3.468078] systemd[1]: Set hostname to . [ 3.470157] random: systemd: uninitialized urandom read (16 bytes read) [ 3.472728] systemd[1]: Initializing machine ID from random generator. [ 3.499290] random: ln: uninitialized urandom read (6 bytes read) [ 3.594506] random: systemd: uninitialized urandom read (16 bytes read) [ 3.596952] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 3.601240] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 3.605573] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ OK ] Reached target Timers. [ OK ] Listening on Journal Socket. Starting Setup Virtual Console... Starting Create Volatile Files and Directories... [ OK ] Started Memstrack Anylazing Service. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Swap. Starting Apply Kernel Variables... [ OK ] Reached target Paths. [ OK ] Reached target Slices. [ OK ] Reached target Local Encrypted Volumes. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Setup Virtual Console. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.185484] device-mapper: uevent: version 1.0.3 [ 4.187747] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 4.838661] random: fast init done [ 4.852613] virtio_net virtio0 ens2: renamed from eth0 [ 4.857123] scsi host0: ata_piix [ 4.860239] scsi host1: ata_piix [ 4.861723] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.864096] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.512320] dracut-initqueue[581]: RTNETLINK answers: File exists [ 9.880202] random: crng init done [ 9.882467] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 10.222492] 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 Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped target Basic System. [ OK ] Stopped target Paths. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped target Swap. Stopping udev Kernel Device Manager... [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.353059] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.634317] SELinux: Disabled at runtime. [ 11.696240] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 11.705693] systemd[1]: Detected virtualization kvm. [ 11.707459] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.249709] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.253564] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.258179] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.262918] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.266836] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.275531] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.282289] systemd[1]: Mounting POSIX Message Queue File System... Mounting POSIX Message Queue File System... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Started Dispatch Password Requests to Console Directory Watch. Mounting Huge Pages File System... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Created slice system-serial\x2dgetty.slice. Starting Remount Root and Kernel File Systems... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Listening on udev Control Socket. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. Mounting Kernel Debug File System... [ OK ] Started Forward Password Requests to Wall Directory Watch. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Created slice User and Session Slice. [ OK ] Created slice system-getty.slice. Activating swap /dev/disk/by-label/SWAP... [ OK ] Reached target Paths. Starting Apply Kernel Variables... [ OK ] Stopped target Initrd Root File System. [ OK ] Listening on udev Kernel Socket. [ 12.415537] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Listening on Process Core Dump Socket. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Slices. [ OK ] Reached target rpc_pipefs.target. Starting udev Coldplug all Devices... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Journal Service. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Mounted Huge Pages File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Kernel Debug File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... Starting Configure read-only root support... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 12.669406] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Coldplug all Devices. [ OK ] Started udev Kernel Device Manager. [ 13.023590] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.075332] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.150214] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.163281] EDAC sbridge: Ver: 1.1.2 [ 14.219027] Key type dns_resolver registered [ 14.524650] NFS: Registering the id_resolver key type [ 14.526441] Key type id_resolver registered [ 14.528023] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Load/Save Random Seed... Starting Create Volatile Files and Directories... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started dnf makecache --timer. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started D-Bus System Message Bus. [ OK ] Reached target sshd-keygen.target. Starting Network Manager... Starting Login Service... [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. Starting Restore /run/initramfs on shutdown... [ OK ] Started irqbalance daemon. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Network Manager Wait Online... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Command Scheduler. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg330-client login: [ 69.018350] libcfs: loading out-of-tree module taints kernel. [ 69.192786] Key type ._llcrypt registered [ 69.216616] Key type .llcrypt registered [ 69.804746] hrtimer: interrupt took 9733532 ns [ 69.977260] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 69.999299] alg: No test for adler32 (adler32-zlib) [ 71.467115] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [ 72.600504] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 74.399673] Key type lgssc registered [ 75.983367] Lustre: Echo OBD driver; http://www.lustre.org/ [ 219.913790] Lustre: Mounted lustre-client [ 223.968187] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 238.496549] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing check_logdir /tmp/testlogs/ [ 242.009371] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing yml_node [ 245.376342] Lustre: DEBUG MARKER: Client: 2.16.61.51 [ 245.727608] Lustre: lustre-OST0000-osc-ffff8e2b11a55000: disconnect after 23s idle [ 247.287527] Lustre: DEBUG MARKER: MDS: 2.16.61.51 [ 249.180215] Lustre: DEBUG MARKER: OSS: 2.16.61.51 [ 250.643696] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Sun Nov 16 17:47:20 EST 2025 [ 266.102584] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 267.545105] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 269.267641] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 279.534182] LustreError: 8979:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 279.557823] Lustre: Unmounted lustre-client [ 378.913458] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 382.447953] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 400.620773] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 434.825435] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 17:50:24 (1763333424) [ 437.271635] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 438.893844] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 17:50:28 (1763333428) [ 469.419718] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 472.792938] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 491.594162] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 497.185930] Lustre: Mounted lustre-client [ 500.045802] LustreError: 14425:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b181ef800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 500.062664] LustreError: 14425:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 500.066832] LustreError: 14425:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 500.121959] Lustre: Unmounted lustre-client [ 535.234589] Key type lgssc unregistered [ 535.582527] LNet: 14833:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 535.590995] LNetError: 14833:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 535.609441] LNet: Removed LNI 192.168.203.30@tcp [ 536.407209] Key type .llcrypt unregistered [ 536.410454] Key type ._llcrypt unregistered [ 551.335580] Key type ._llcrypt registered [ 551.343730] Key type .llcrypt registered [ 552.875216] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 17:52:22 (1763333542) [ 554.636459] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 554.832174] alg: No test for adler32 (adler32-zlib) [ 556.234197] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [ 556.686920] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 558.472947] Key type lgssc registered [ 559.598445] Lustre: Echo OBD driver; http://www.lustre.org/ [ 634.934288] Lustre: Mounted lustre-client [ 724.568071] LustreError: 18266:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b1819a800: lov tgt 1990 not cleaned! deathrow=0, lovrc=1 [ 724.604640] LustreError: 18266:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 724.653230] Lustre: Unmounted lustre-client [ 751.315658] Key type lgssc unregistered [ 751.553384] LNet: 18730:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 751.564165] LNetError: 18730:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 752.620245] LNet: Removed LNI 192.168.203.30@tcp [ 753.115367] Key type .llcrypt unregistered [ 753.118616] Key type ._llcrypt unregistered [ 761.680235] Key type ._llcrypt registered [ 761.681790] Key type .llcrypt registered [ 762.950036] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 17:55:52 (1763333752) [ 764.949793] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 765.003174] alg: No test for adler32 (adler32-zlib) [ 766.148666] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [ 766.448396] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 768.167183] Key type lgssc registered [ 769.468067] Lustre: Echo OBD driver; http://www.lustre.org/ [ 815.026166] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 818.158986] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 833.781074] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 840.240820] Lustre: Mounted lustre-client [ 849.754713] LustreError: 21840:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b03799000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 849.792369] LustreError: 21840:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 849.855256] Lustre: Unmounted lustre-client [ 877.235220] Key type lgssc unregistered [ 877.520994] LNet: 22246:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 877.530258] LNetError: 22246:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 877.552395] LNet: Removed LNI 192.168.203.30@tcp [ 878.289841] Key type .llcrypt unregistered [ 878.291844] Key type ._llcrypt unregistered [ 889.320478] Key type ._llcrypt registered [ 889.329280] Key type .llcrypt registered [ 890.584743] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 17:58:00 (1763333880) [ 893.683273] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 893.704873] alg: No test for adler32 (adler32-zlib) [ 894.789165] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [ 895.024888] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 896.728184] Key type lgssc registered [ 898.102703] Lustre: Echo OBD driver; http://www.lustre.org/ [ 946.096556] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 950.413520] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 969.434832] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 971.170931] Lustre: Mounted lustre-client [ 976.818751] LustreError: 24959:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b10ede000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 976.856670] LustreError: 24959:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 976.909285] Lustre: Unmounted lustre-client [ 1004.100252] Key type lgssc unregistered [ 1004.348615] LNet: 25360:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1004.357890] LNetError: 25360:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1004.378601] LNet: Removed LNI 192.168.203.30@tcp [ 1004.879826] Key type .llcrypt unregistered [ 1004.881466] Key type ._llcrypt unregistered [ 1017.865555] Key type ._llcrypt registered [ 1017.867168] Key type .llcrypt registered [ 1019.091937] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 18:00:08 (1763334008) [ 1021.725856] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1021.749451] alg: No test for adler32 (adler32-zlib) [ 1022.816858] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [ 1023.060760] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 1024.775187] Key type lgssc registered [ 1026.039353] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1066.075190] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1069.241908] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1084.599522] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1095.748125] Lustre: Mounted lustre-client [ 1100.815923] Lustre: lustre-MDT0000-mdc-ffff8e2b0534e800: Connection to lustre-MDT0000 (at 192.168.203.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1105.906989] Lustre: lustre-MDT0001-mdc-ffff8e2b0534e800: Connection to lustre-MDT0001 (at 192.168.203.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1113.203595] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1122.143153] Lustre: 26316:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763334097/real 1763334097] req@000000004f4f6fb2 x1848989727070208/t0(0) o400->MGC192.168.203.130@tcp@192.168.203.130@tcp:26/25 lens 224/224 e 0 to 1 dl 1763334113 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1122.189501] LustreError: MGC192.168.203.130@tcp: Connection to MGS (at 192.168.203.130@tcp) was lost; in progress operations using this service will fail [ 1123.528344] LustreError: 28550:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1123.593758] Lustre: Unmounted lustre-client [ 1140.038638] Key type lgssc unregistered [ 1140.294678] LNet: 28940:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1140.301758] LNetError: 28940:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1140.322381] LNet: Removed LNI 192.168.203.30@tcp [ 1140.820102] Key type .llcrypt unregistered [ 1140.822836] Key type ._llcrypt unregistered [ 1155.047079] Key type ._llcrypt registered [ 1155.048846] Key type .llcrypt registered [ 1156.394840] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 18:02:26 (1763334146) [ 1158.606210] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1158.715153] alg: No test for adler32 (adler32-zlib) [ 1159.820832] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [ 1160.175435] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 1161.887824] Key type lgssc registered [ 1163.091898] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1204.242196] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1208.301165] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1225.715919] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1231.378146] Lustre: Mounted lustre-client [ 1236.459936] Lustre: lustre-OST0000-osc-ffff8e2b053d6800: Connection to lustre-OST0000 (at 192.168.203.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1242.993819] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1253.310038] LustreError: 32063:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1253.388114] Lustre: Unmounted lustre-client [ 1285.916103] Key type lgssc unregistered [ 1286.194203] LNet: 32464:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1286.198198] LNetError: 32464:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1286.238088] LNet: Removed LNI 192.168.203.30@tcp [ 1286.723852] Key type .llcrypt unregistered [ 1286.726592] Key type ._llcrypt unregistered [ 1298.598205] Key type ._llcrypt registered [ 1298.599969] Key type .llcrypt registered [ 1299.741235] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 18:04:49 (1763334289) [ 1301.492321] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1301.547983] alg: No test for adler32 (adler32-zlib) [ 1302.620111] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [ 1302.852126] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 1304.495166] Key type lgssc registered [ 1305.633304] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1346.842722] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1349.467114] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1365.177627] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1376.307248] Lustre: Mounted lustre-client [ 1380.102345] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1385.389977] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1396.831546] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2025-11-1618h06m26s [ 1398.737720] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1401.827430] Lustre: lustre-MDT0000-mdc-ffff8e2b1819e000: Connection to lustre-MDT0000 (at 192.168.203.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1408.367135] LustreError: 36163:0:(lmv_obd.c:1435:lmv_statfs()) lustre-MDT0000-mdc-ffff8e2b1819e000: can't stat MDS #0: rc = -19 [ 1409.729938] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1419.240831] LustreError: MGC192.168.203.130@tcp: Connection to MGS (at 192.168.203.130@tcp) was lost; in progress operations using this service will fail [ 1419.256120] Lustre: Evicted from MGS (at 192.168.203.130@tcp) after server handle changed from 0x432b654a83d5c709 to 0x432b654a83d5ca9e [ 1419.271938] Lustre: MGC192.168.203.130@tcp: Connection restored to 192.168.203.130@tcp (at 192.168.203.130@tcp) [ 1420.337522] LustreError: lustre-MDT0000-mdc-ffff8e2b1819e000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1420.360822] Lustre: lustre-MDT0000-mdc-ffff8e2b1819e000: Connection restored to 192.168.203.130@tcp (at 192.168.203.130@tcp) [ 1423.010560] Lustre: DEBUG MARKER: Wait for df (36163) ... [ 1424.431202] Lustre: DEBUG MARKER: done [ 1425.669244] LustreError: 36777:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b1819e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1425.677685] LustreError: 36777:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1425.726330] Lustre: Unmounted lustre-client [ 1459.169630] Key type lgssc unregistered [ 1459.426101] LNet: 37180:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1459.433596] LNetError: 37180:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1459.453442] LNet: Removed LNI 192.168.203.30@tcp [ 1459.973456] Key type .llcrypt unregistered [ 1459.975211] Key type ._llcrypt unregistered [ 1499.146677] Key type ._llcrypt registered [ 1499.148227] Key type .llcrypt registered [ 1499.601887] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1499.624867] alg: No test for adler32 (adler32-zlib) [ 1500.671285] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [ 1500.857534] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 1502.520548] Key type lgssc registered [ 1503.566614] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1562.273995] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1565.113982] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1582.049330] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1623.595686] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 18:10:13 (1763334613) [ 1646.970474] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1650.212433] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1665.215951] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1676.347799] Lustre: Mounted lustre-client [ 1681.435899] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2025-11-1618h11m10s [ 1682.953364] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1689.248531] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1691.556653] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1712.095596] Lustre: 44044:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763334682/real 1763334682] req@000000004ae2b319 x1848990227241344/t0(0) o101->lustre-MDT0000-mdc-ffff8e2b048fb800@192.168.203.130@tcp:12/10 lens 576/1152 e 0 to 1 dl 1763334702 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:0 [ 1712.127074] Lustre: lustre-MDT0000-mdc-ffff8e2b048fb800: Connection to lustre-MDT0000 (at 192.168.203.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1712.168230] Lustre: lustre-MDT0000-mdc-ffff8e2b048fb800: Connection restored to 192.168.203.130@tcp (at 192.168.203.130@tcp) [ 1733.477555] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1736.185382] Lustre: DEBUG MARKER: done [ 1739.795762] LustreError: 44524:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b048fb800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1739.822262] LustreError: 44524:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1739.884366] Lustre: Unmounted lustre-client [ 1774.227519] Key type lgssc unregistered [ 1774.501950] LNet: 44928:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1774.510786] LNetError: 44928:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1774.532903] LNet: Removed LNI 192.168.203.30@tcp [ 1775.165721] Key type .llcrypt unregistered [ 1775.169762] Key type ._llcrypt unregistered [ 1811.135909] Key type ._llcrypt registered [ 1811.141214] Key type .llcrypt registered [ 1811.794370] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1811.815498] alg: No test for adler32 (adler32-zlib) [ 1813.047395] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [ 1813.369821] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 1815.063228] Key type lgssc registered [ 1816.364594] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1889.984443] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1893.704583] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1911.924588] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1951.954883] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 18:15:41 (1763334941) [ 1953.466544] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1955.089648] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 18:15:44 (1763334944) [ 1973.801826] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 18:16:03 (1763334963) [ 1995.345866] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1998.356161] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2013.816031] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2015.287636] Lustre: Mounted lustre-client [ 2018.198091] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 2020.716415] LustreError: 52761:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b04926000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2020.756222] LustreError: 52761:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2020.840862] Lustre: Unmounted lustre-client [ 2029.626386] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 2071.889113] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2075.934974] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2076.421803] Lustre: Mounted lustre-client [ 2080.363975] LustreError: 54018:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b0534f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2080.388625] LustreError: 54018:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2080.395852] LustreError: 54018:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 2080.471212] Lustre: Unmounted lustre-client [ 2140.246761] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2143.747448] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2144.215862] Lustre: Mounted lustre-client [ 2147.165918] LustreError: 55146:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b18541000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2147.201598] LustreError: 55146:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2147.208210] LustreError: 55146:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 2147.285310] Lustre: Unmounted lustre-client [ 2163.543783] Lustre: DEBUG MARKER: files compared the same [ 2186.991379] Key type lgssc unregistered [ 2187.261964] LNet: 55852:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2187.273415] LNetError: 55852:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2187.288166] LNet: Removed LNI 192.168.203.30@tcp [ 2188.199674] Key type .llcrypt unregistered [ 2188.206089] Key type ._llcrypt unregistered [ 2203.438893] Key type ._llcrypt registered [ 2203.443249] Key type .llcrypt registered [ 2204.693310] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 18:19:54 (1763335194) [ 2207.179633] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2207.199187] alg: No test for adler32 (adler32-zlib) [ 2208.369681] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [ 2208.660663] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 2210.359166] Key type lgssc registered [ 2211.497976] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2254.520973] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2257.545611] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2273.313831] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2279.490996] Lustre: Mounted lustre-client [ 2282.799105] LustreError: 58943:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b1819a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2282.821126] LustreError: 58943:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2282.878664] Lustre: Unmounted lustre-client [ 2308.625812] Key type lgssc unregistered [ 2308.843698] LNet: 59347:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2308.848906] LNetError: 59347:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2308.875531] LNet: Removed LNI 192.168.203.30@tcp [ 2309.374678] Key type .llcrypt unregistered [ 2309.376432] Key type ._llcrypt unregistered [ 2321.507934] Key type ._llcrypt registered [ 2321.509884] Key type .llcrypt registered [ 2322.503380] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 18:21:52 (1763335312) [ 2324.497700] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2324.525724] alg: No test for adler32 (adler32-zlib) [ 2325.564033] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [ 2325.728671] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 2327.383159] Key type lgssc registered [ 2328.372265] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2404.297325] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2435.544213] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2439.432635] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2466.638888] Key type lgssc unregistered [ 2466.904724] LNet: 62742:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2466.918793] LNetError: 62742:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2466.935627] LNet: Removed LNI 192.168.203.30@tcp [ 2467.618471] Key type .llcrypt unregistered [ 2467.623789] Key type ._llcrypt unregistered [ 2479.642178] Key type ._llcrypt registered [ 2479.643970] Key type .llcrypt registered [ 2480.698950] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 18:24:30 (1763335470) [ 2483.530941] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2483.546637] alg: No test for adler32 (adler32-zlib) [ 2484.606744] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [ 2484.807787] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 2486.456796] Key type lgssc registered [ 2487.356614] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2554.326057] Lustre: Mounted lustre-client [ 2561.297637] LustreError: 65229:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b088c3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2561.334049] LustreError: 65229:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2561.417756] Lustre: Unmounted lustre-client [ 2595.327378] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 18:26:25 (1763335585) [ 2693.928814] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2713.105887] Lustre: Mounted lustre-client [ 2719.307613] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2719.312703] LustreError: 69189:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff8e2b061e1000: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 2719.330489] Lustre: 63720:0:(llite_lib.c:4226:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.203.130@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 2729.586987] LustreError: 69189:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2729.594926] LustreError: 69189:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 2729.652774] Lustre: Unmounted lustre-client [ 2767.067838] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 18:29:17 (1763335757) [ 2774.853734] Key type lgssc unregistered [ 2775.033672] LNet: 70397:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2775.037729] LNetError: 70397:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2775.052615] LNet: Removed LNI 192.168.203.30@tcp [ 2775.412060] Key type .llcrypt unregistered [ 2775.413795] Key type ._llcrypt unregistered [ 2782.691910] Key type ._llcrypt registered [ 2782.693456] Key type .llcrypt registered [ 2783.059980] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2783.069926] alg: No test for adler32 (adler32-zlib) [ 2784.032200] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [ 2784.186880] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 2785.847197] Key type lgssc registered [ 2786.855078] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2823.533512] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2826.586953] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2840.280775] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2856.456865] Lustre: Mounted lustre-client [ 2858.515328] LustreError: 72917:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b18199800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2858.527978] LustreError: 72917:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2858.560148] Lustre: Unmounted lustre-client [ 2880.790587] Key type lgssc unregistered [ 2880.991785] LNet: 73320:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2881.001192] LNetError: 73320:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2881.017179] LNet: Removed LNI 192.168.203.30@tcp [ 2881.487492] Key type .llcrypt unregistered [ 2881.488949] Key type ._llcrypt unregistered [ 2888.843675] Key type ._llcrypt registered [ 2888.845113] Key type .llcrypt registered [ 2889.376537] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2889.386495] alg: No test for adler32 (adler32-zlib) [ 2890.358242] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [ 2890.513944] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 2892.167182] Key type lgssc registered [ 2892.894294] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2974.597876] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2977.306852] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2990.636630] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3001.368679] Lustre: Mounted lustre-client [ 3002.618047] LustreError: 76254:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b0379a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3002.628704] LustreError: 76254:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3002.668190] Lustre: Unmounted lustre-client [ 3023.752363] Key type lgssc unregistered [ 3023.896626] LNet: 76653:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3023.906873] LNetError: 76653:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3023.922667] LNet: Removed LNI 192.168.203.30@tcp [ 3024.420180] Key type .llcrypt unregistered [ 3024.423592] Key type ._llcrypt unregistered [ 3037.443439] Key type ._llcrypt registered [ 3037.445375] Key type .llcrypt registered [ 3038.387459] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 18:33:48 (1763336028) [ 3039.954541] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3039.963314] alg: No test for adler32 (adler32-zlib) [ 3040.975154] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [ 3041.240904] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 3042.887170] Key type lgssc registered [ 3043.741680] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3073.463799] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3075.685357] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3088.184990] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3090.670971] Lustre: Mounted lustre-client [ 3098.031943] LustreError: 79767:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b10edc000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3098.076347] LustreError: 79767:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3098.128455] Lustre: Unmounted lustre-client [ 3098.505348] Lustre: Mounted lustre-client [ 3100.343565] LustreError: 79845:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b05132000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3100.357724] LustreError: 79845:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3100.361775] LustreError: 79845:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 3100.447477] Lustre: Unmounted lustre-client [ 3122.810204] Key type lgssc unregistered [ 3123.052508] LNet: 80247:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3123.061309] LNetError: 80247:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3123.080235] LNet: Removed LNI 192.168.203.30@tcp [ 3123.453951] Key type .llcrypt unregistered [ 3123.455747] Key type ._llcrypt unregistered [ 3130.426353] Key type ._llcrypt registered [ 3130.427959] Key type .llcrypt registered [ 3130.679135] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3130.691139] alg: No test for adler32 (adler32-zlib) [ 3131.657828] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [ 3131.814139] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 3133.431688] Key type lgssc registered [ 3134.107383] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3164.233390] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3166.844329] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3178.949573] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3180.440155] Lustre: Mounted lustre-client [ 3186.704765] LustreError: 82784:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b063e3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3186.720201] LustreError: 82784:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3186.759919] Lustre: Unmounted lustre-client [ 3218.248373] Key type lgssc unregistered [ 3218.455457] LNet: 83186:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3218.464709] LNetError: 83186:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3218.492235] LNet: Removed LNI 192.168.203.30@tcp [ 3219.081139] Key type .llcrypt unregistered [ 3219.082800] Key type ._llcrypt unregistered [ 3231.094081] Key type ._llcrypt registered [ 3231.096892] Key type .llcrypt registered [ 3232.238237] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 18:37:02 (1763336222) [ 3234.388404] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3234.435594] alg: No test for adler32 (adler32-zlib) [ 3235.513149] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [ 3235.745195] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 3237.439538] Key type lgssc registered [ 3238.693151] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3275.535678] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3278.098575] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3291.722591] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3294.252989] Lustre: Mounted lustre-client [ 3299.352756] Lustre: lustre: root_squash is set to 0:0 [ 3305.025162] Lustre: lustre: nosquash_nids is cleared [ 3312.676628] Lustre: lustre: root_squash is set to 500:500 [ 3326.991684] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.203.30@tcp 192.168.0.[2,10]@tcp [ 3333.831928] Lustre: lustre: nosquash_nids set to 192.168.203.30/24@tcp [ 3335.058928] LustreError: 86674:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b20060000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3335.074920] LustreError: 86674:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3335.133159] Lustre: Unmounted lustre-client [ 3360.659967] Key type lgssc unregistered [ 3360.962481] LNet: 87075:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3360.971912] LNetError: 87075:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3360.992256] LNet: Removed LNI 192.168.203.30@tcp [ 3362.055701] Key type .llcrypt unregistered [ 3362.070449] Key type ._llcrypt unregistered [ 3375.559548] Key type ._llcrypt registered [ 3375.561192] Key type .llcrypt registered [ 3376.789519] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 18:39:26 (1763336366) [ 3379.040460] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3380.154906] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 18:39:30 (1763336370) [ 3381.910224] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3382.014338] alg: No test for adler32 (adler32-zlib) [ 3383.046942] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [ 3383.185898] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 3384.824449] Key type lgssc registered [ 3385.755158] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3422.726726] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3425.705523] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3437.597252] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3437.950468] Lustre: lustre: root_squash is set to 500:500 [ 3437.968609] Lustre: lustre: nosquash_nids set to 192.168.203.30/24@tcp [ 3453.453212] Lustre: Mounted lustre-client [ 3457.152175] LustreError: 90541:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b1819d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3457.168389] LustreError: 90541:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3457.206744] Lustre: Unmounted lustre-client [ 3479.889528] Key type lgssc unregistered [ 3480.149264] LNet: 90943:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3480.157020] LNetError: 90943:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3480.175148] LNet: Removed LNI 192.168.203.30@tcp [ 3480.744608] Key type .llcrypt unregistered [ 3480.749502] Key type ._llcrypt unregistered [ 3492.683409] Key type ._llcrypt registered [ 3492.685112] Key type .llcrypt registered [ 3493.929205] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 3495.421340] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 18:41:25 (1763336485) [ 3509.105733] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3509.127157] alg: No test for adler32 (adler32-zlib) [ 3510.048357] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [ 3510.168952] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 3511.775405] Key type lgssc registered [ 3512.535744] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3568.374734] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3570.770716] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3585.594837] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3641.799375] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3644.906017] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3659.986721] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3674.892987] Lustre: Mounted lustre-client [ 3698.082835] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8e2b0318d800.ost_server_uuid 50 [ 3699.517116] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8e2b0318d800.ost_server_uuid in FULL state after 0 sec [ 3700.172708] Lustre: Mounted lustre-client [ 3702.818358] LustreError: 97834:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b063e4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3702.845170] LustreError: 97834:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 3702.921502] Lustre: Unmounted lustre-client [ 3704.110053] LustreError: 97853:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b0318d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3704.115386] LustreError: 97853:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 3704.134379] LustreError: 97853:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 3704.137236] LustreError: 97853:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 3704.185956] Lustre: Unmounted lustre-client [ 3735.867593] Key type lgssc unregistered [ 3736.036784] LNet: 98405:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3736.040814] LNetError: 98405:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3736.062796] LNet: Removed LNI 192.168.203.30@tcp [ 3736.513933] Key type .llcrypt unregistered [ 3736.516493] Key type ._llcrypt unregistered [ 3771.303891] Key type ._llcrypt registered [ 3771.306109] Key type .llcrypt registered [ 3771.686415] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3771.696593] alg: No test for adler32 (adler32-zlib) [ 3772.752777] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [ 3772.946313] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 3774.591165] Key type lgssc registered [ 3775.586618] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3844.497869] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3847.684433] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3865.035973] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3911.031968] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 18:48:20 (1763336900) [ 3990.227740] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3993.649554] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4011.666149] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4013.222149] Lustre: Mounted lustre-client [ 4034.015117] Lustre: 99190:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763337009/real 1763337009] req@000000001a61ec4f x1848992610653312/t0(0) o400->lustre-OST0000-osc-ffff8e2b063e0000@192.168.203.130@tcp:28/4 lens 224/224 e 0 to 1 dl 1763337025 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4034.057654] Lustre: lustre-OST0000-osc-ffff8e2b063e0000: Connection to lustre-OST0000 (at 192.168.203.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4039.010549] Lustre: 99189:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763337014/real 1763337014] req@00000000eb55310e x1848992610653824/t0(0) o400->lustre-OST0000-osc-ffff8e2b063e0000@192.168.203.130@tcp:28/4 lens 224/224 e 0 to 1 dl 1763337030 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4044.265203] Lustre: lustre-MDT0000-mdc-ffff8e2b063e0000: Connection to lustre-MDT0000 (at 192.168.203.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4044.315814] Lustre: lustre-OST0000-osc-ffff8e2b063e0000: Connection restored to 192.168.203.130@tcp (at 192.168.203.130@tcp) [ 4045.279657] Lustre: 99188:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763337020/real 1763337020] req@00000000f48d66dd x1848992610654336/t0(0) o400->lustre-OST0000-osc-ffff8e2b063e0000@192.168.203.130@tcp:28/4 lens 224/224 e 0 to 1 dl 1763337036 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4050.399597] Lustre: 99191:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763337025/real 1763337025] req@00000000c2da0b91 x1848992610654848/t0(0) o400->lustre-OST0000-osc-ffff8e2b063e0000@192.168.203.130@tcp:28/4 lens 224/224 e 0 to 1 dl 1763337041 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4065.759538] Lustre: 99189:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763337040/real 1763337040] req@000000003d983ff1 x1848992610656640/t0(0) o400->MGC192.168.203.130@tcp@192.168.203.130@tcp:26/25 lens 224/224 e 0 to 1 dl 1763337056 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4065.775867] LustreError: MGC192.168.203.130@tcp: Connection to MGS (at 192.168.203.130@tcp) was lost; in progress operations using this service will fail [ 4065.825593] Lustre: Evicted from MGS (at 192.168.203.130@tcp) after server handle changed from 0xc13bbef7b158f827 to 0xc13bbef7b158faa4 [ 4065.845808] Lustre: MGC192.168.203.130@tcp: Connection restored to 192.168.203.130@tcp (at 192.168.203.130@tcp) [ 4070.712787] Lustre: lustre-MDT0000-mdc-ffff8e2b063e0000: Connection restored to 192.168.203.130@tcp (at 192.168.203.130@tcp) [ 4074.135995] LustreError: 107061:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b063e0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4074.167740] LustreError: 107061:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4074.237517] Lustre: Unmounted lustre-client [ 4099.896261] Key type lgssc unregistered [ 4100.172109] LNet: 107463:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4100.182809] LNetError: 107463:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4100.208481] LNet: Removed LNI 192.168.203.30@tcp [ 4100.789486] Key type .llcrypt unregistered [ 4100.793993] Key type ._llcrypt unregistered [ 4115.983947] Key type ._llcrypt registered [ 4115.985642] Key type .llcrypt registered [ 4117.286849] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 18:51:46 (1763337106) [ 4131.529783] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4131.565996] alg: No test for adler32 (adler32-zlib) [ 4132.597155] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [ 4132.792289] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 4134.471216] Key type lgssc registered [ 4135.839394] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4166.748392] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4250.489783] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4253.481669] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4271.965985] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4273.532299] Lustre: Mounted lustre-client [ 4275.073729] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 4276.543439] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 18:54:26 (1763337266) [ 4474.525459] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4484.785621] LustreError: 118225:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4484.817152] Lustre: Unmounted lustre-client [ 4583.868470] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4586.569679] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4603.237346] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4636.533294] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 19:00:26 (1763337626) [ 4712.948255] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4716.500509] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4735.441901] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4736.119287] Lustre: Mounted lustre-client [ 4739.216367] LustreError: 125649:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b04276000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4739.237176] LustreError: 125649:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4739.241498] LustreError: 125649:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4739.318173] Lustre: Unmounted lustre-client [ 4762.597162] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 19:02:32 (1763337752) [ 4841.080301] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4844.721315] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4864.820840] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4865.395031] Lustre: Mounted lustre-client [ 4868.945400] LustreError: 129694:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b0759e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4868.978846] LustreError: 129694:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4868.982871] LustreError: 129694:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4869.059387] Lustre: Unmounted lustre-client [ 4894.843458] Key type lgssc unregistered [ 4895.045333] LNet: 130096:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4895.059829] LNetError: 130096:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4895.072637] LNet: Removed LNI 192.168.203.30@tcp [ 4895.660752] Key type .llcrypt unregistered [ 4895.662676] Key type ._llcrypt unregistered [ 4909.518852] Key type ._llcrypt registered [ 4909.520422] Key type .llcrypt registered [ 4910.663836] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 19:05:00 (1763337900) [ 4912.940850] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4913.074232] alg: No test for adler32 (adler32-zlib) [ 4914.186556] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [ 4914.530496] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 4916.248938] Key type lgssc registered [ 4917.526040] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4963.165691] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4966.610622] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4984.644701] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4990.513296] Lustre: Mounted lustre-client [ 5003.036541] LustreError: 133197:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b05132000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5003.069194] LustreError: 133197:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5003.154031] Lustre: Unmounted lustre-client [ 5031.125822] Key type lgssc unregistered [ 5031.359177] LNet: 133599:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5031.364444] LNetError: 133599:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5031.388502] LNet: Removed LNI 192.168.203.30@tcp [ 5032.031838] Key type .llcrypt unregistered [ 5032.033589] Key type ._llcrypt unregistered [ 5044.705941] Key type ._llcrypt registered [ 5044.708143] Key type .llcrypt registered [ 5045.819970] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 19:07:15 (1763338035) [ 5047.643539] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5047.763528] alg: No test for adler32 (adler32-zlib) [ 5048.849817] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [ 5049.107756] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 5050.794048] Key type lgssc registered [ 5051.810783] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5095.836428] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5098.862929] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5117.300845] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5124.146058] Lustre: Mounted lustre-client [ 5129.195534] Lustre: lustre-OST0000-osc-ffff8e2b0759e800: Connection to lustre-OST0000 (at 192.168.203.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5140.069859] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff8e2b0759e800.ost_server_uuid 50 [ 5141.861239] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 5152.396905] LustreError: 137151:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b0759e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5152.419101] LustreError: 137151:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5152.518317] Lustre: Unmounted lustre-client [ 5173.878389] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 19:09:23 (1763338163) [ 5202.406322] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5206.148180] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5224.508485] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5246.219767] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5247.042211] Lustre: Mounted lustre-client [ 5252.073987] Lustre: lustre-OST0000-osc-ffff8e2b11247800: Connection to lustre-OST0000 (at 192.168.203.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5276.530582] LustreError: 139804:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b11247800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5276.551790] LustreError: 139804:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 5276.554388] LustreError: 139804:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5276.617117] Lustre: Unmounted lustre-client [ 5322.411151] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 19:11:52 (1763338312) [ 5352.997416] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5357.042152] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5375.382957] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5392.714231] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5393.404732] Lustre: Mounted lustre-client [ 5398.509780] Lustre: lustre-OST0000-osc-ffff8e2b1819e000: Connection to lustre-OST0000 (at 192.168.203.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5421.865174] LustreError: 142570:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b1819e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5421.882116] LustreError: 142570:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 5421.920733] LustreError: 142570:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 5421.923806] LustreError: 142570:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 5422.015145] Lustre: Unmounted lustre-client [ 5470.449743] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 19:14:20 (1763338460) [ 5561.672540] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5565.159202] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5583.193566] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5663.783994] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5667.152139] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5685.547515] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5722.778757] Lustre: Mounted lustre-client [ 5763.860282] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5768.886281] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 3 sec [ 5770.179623] LustreError: 149201:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b20064000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5770.203077] LustreError: 149201:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 5770.244121] LustreError: 149201:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5770.252924] LustreError: 149201:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 5770.316513] Lustre: Unmounted lustre-client [ 5800.492726] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 19:19:50 (1763338790) [ 5831.798929] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5835.635344] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5852.769424] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5881.272408] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5914.861072] Lustre: Mounted lustre-client [ 5953.446081] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5955.093194] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 5961.215164] Lustre: lustre-OST0001-osc-ffff8e2b063e2000: Connection to lustre-OST0001 (at 192.168.203.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5965.350652] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5975.661795] LustreError: 152792:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 5975.666912] LustreError: 152792:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5975.692317] Lustre: Unmounted lustre-client [ 6024.678131] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 19:23:34 (1763339014) [ 6049.657806] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6052.892494] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6068.320644] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6069.096788] Lustre: Mounted lustre-client [ 6087.847605] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6089.484916] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6101.970239] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8e2b1819f800.ost_server_uuid 50 [ 6103.354660] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8e2b1819f800.ost_server_uuid in FULL state after 0 sec [ 6105.634862] LustreError: 156218:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b1819f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6105.642589] LustreError: 156218:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 6105.653451] LustreError: 156218:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 6105.710144] Lustre: Unmounted lustre-client [ 6105.923622] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 6105.926661] Lustre: Skipped 3 previous similar messages [ 6106.036705] Lustre: Mounted lustre-client [ 6106.855265] LustreError: 156281:0:(sec.c:410:import_sec_validate_get()) import 00000000aa0eb513 (NEW) with no sec [ 6167.646969] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 19:25:57 (1763339157) [ 6196.550753] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6200.168268] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6217.348415] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6234.060313] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6234.664956] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 6234.816614] Lustre: Mounted lustre-client [ 6244.092407] LustreError: 159129:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b1819f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6244.097943] LustreError: 159129:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6244.114673] LustreError: 159129:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 6244.117818] LustreError: 159129:0:(obd_class.h:479:obd_check_dev()) Skipped 18 previous similar messages [ 6244.186355] Lustre: Unmounted lustre-client [ 6244.189490] Lustre: Skipped 1 previous similar message [ 6279.486709] Key type lgssc unregistered [ 6279.741429] LNet: 159576:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6279.748409] LNetError: 159576:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6279.770084] LNet: Removed LNI 192.168.203.30@tcp [ 6280.419655] Key type .llcrypt unregistered [ 6280.421064] Key type ._llcrypt unregistered [ 6296.187400] Key type ._llcrypt registered [ 6296.189563] Key type .llcrypt registered [ 6297.455495] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 19:28:07 (1763339287) [ 6298.274463] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6298.303503] alg: No test for adler32 (adler32-zlib) [ 6299.329429] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [ 6299.522592] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 6301.184552] Key type lgssc registered [ 6302.405658] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6342.400043] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6345.498500] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6362.123726] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6376.741615] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6378.191499] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 6379.351633] Lustre: Mounted lustre-client [ 6387.273111] LustreError: lustre-MDT0001-mdc-ffff8e2b05e24800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 6387.315679] Lustre: lustre-MDT0001-mdc-ffff8e2b05e24800: Connection restored to 192.168.203.130@tcp (at 192.168.203.130@tcp) [ 6390.902580] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6392.296236] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6410.258791] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 6412.910823] LustreError: 163817:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b05e24800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6412.924043] LustreError: 163817:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 6413.009253] Lustre: Unmounted lustre-client [ 6441.638620] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 19:30:31 (1763339431) [ 6510.810890] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6514.347252] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6532.798819] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6533.186964] Lustre: Mounted lustre-client [ 6551.337558] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6552.344532] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6557.182717] Lustre: lustre-OST0001-osc-ffff8e2b05130800: Connection to lustre-OST0001 (at 192.168.203.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6565.033521] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6575.350676] LustreError: 168542:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 6575.361975] LustreError: 168542:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 6575.396728] Lustre: Unmounted lustre-client [ 6606.992647] Key type lgssc unregistered [ 6607.253986] LNet: 168947:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6607.266685] LNetError: 168947:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6607.298298] LNet: Removed LNI 192.168.203.30@tcp [ 6607.976679] Key type .llcrypt unregistered [ 6607.978832] Key type ._llcrypt unregistered [ 6645.137251] Key type ._llcrypt registered [ 6645.141661] Key type .llcrypt registered [ 6645.615695] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6645.648033] alg: No test for adler32 (adler32-zlib) [ 6646.702318] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [ 6646.872742] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 6648.536047] Key type lgssc registered [ 6649.697554] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6709.148376] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6711.926709] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6727.498607] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6760.412615] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 19:35:50 (1763339750) [ 6782.748550] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6786.684894] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6802.892750] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6814.283756] Lustre: Mounted lustre-client [ 6857.871721] LustreError: 175030:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b18547000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6857.910888] LustreError: 175030:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6857.959713] Lustre: Unmounted lustre-client [ 6888.809900] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6889.434490] Lustre: Mounted lustre-client [ 6925.854859] LustreError: 175688:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b0a39b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6925.886473] LustreError: 175688:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6925.891952] LustreError: 175688:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 6925.938970] Lustre: Unmounted lustre-client [ 6951.123897] Key type lgssc unregistered [ 6951.330815] LNet: 176093:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6951.340522] LNetError: 176093:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6951.368440] LNet: Removed LNI 192.168.203.30@tcp [ 6951.944639] Key type .llcrypt unregistered [ 6951.949411] Key type ._llcrypt unregistered [ 6963.642205] Key type ._llcrypt registered [ 6963.643915] Key type .llcrypt registered [ 6964.855355] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 19:39:14 (1763339954) [ 6966.827480] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6966.858399] alg: No test for adler32 (adler32-zlib) [ 6967.835801] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [ 6968.106754] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 6969.825908] Key type lgssc registered [ 6970.725305] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7009.539069] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7012.691816] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7026.919900] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7037.979708] Lustre: Mounted lustre-client [ 7053.577183] LustreError: 179349:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b082eb800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7053.600458] LustreError: 179349:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7053.652080] Lustre: Unmounted lustre-client [ 7080.426868] Key type lgssc unregistered [ 7080.721627] LNet: 179752:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7080.727628] LNetError: 179752:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7080.746146] LNet: Removed LNI 192.168.203.30@tcp [ 7081.429483] Key type .llcrypt unregistered [ 7081.431482] Key type ._llcrypt unregistered [ 7094.225670] Key type ._llcrypt registered [ 7094.243583] Key type .llcrypt registered [ 7094.820712] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7094.846190] alg: No test for adler32 (adler32-zlib) [ 7095.968046] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [ 7096.188956] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 7097.831358] Key type lgssc registered [ 7099.049538] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7145.445096] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7149.739626] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7167.747056] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7169.355307] Lustre: Mounted lustre-client [ 7175.623264] LustreError: 182326:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b063e6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7175.647058] LustreError: 182326:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7175.713626] Lustre: Unmounted lustre-client [ 7211.970692] Key type lgssc unregistered [ 7212.251525] LNet: 182730:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7212.263889] LNetError: 182730:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7213.287966] LNet: Removed LNI 192.168.203.30@tcp [ 7213.857144] Key type .llcrypt unregistered [ 7213.863631] Key type ._llcrypt unregistered [ 7226.562161] Key type ._llcrypt registered [ 7226.563604] Key type .llcrypt registered [ 7227.792267] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 19:43:37 (1763340217) [ 7230.390463] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7230.428858] alg: No test for adler32 (adler32-zlib) [ 7231.483442] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [ 7231.662657] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 7233.311194] Key type lgssc registered [ 7234.317454] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7281.766495] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7285.864275] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7302.927909] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7309.877817] Lustre: Mounted lustre-client [ 7326.436209] LustreError: 185990:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b11a51800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7326.454894] LustreError: 185990:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7326.498314] Lustre: Unmounted lustre-client [ 7352.890462] Key type lgssc unregistered [ 7353.141412] LNet: 186392:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7353.146122] LNetError: 186392:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7353.171210] LNet: Removed LNI 192.168.203.30@tcp [ 7353.792553] Key type .llcrypt unregistered [ 7353.794055] Key type ._llcrypt unregistered [ 7364.335122] Key type ._llcrypt registered [ 7364.339147] Key type .llcrypt registered [ 7364.746719] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7364.755189] alg: No test for adler32 (adler32-zlib) [ 7365.870315] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [ 7366.123596] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 7367.815284] Key type lgssc registered [ 7369.102506] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7408.642519] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7411.788387] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7428.923532] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7439.943144] Lustre: Mounted lustre-client [ 7447.557494] LustreError: 188966:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b0a22b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7447.576855] LustreError: 188966:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7447.637338] Lustre: Unmounted lustre-client [ 7470.155315] Key type lgssc unregistered [ 7470.427252] LNet: 189367:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7470.437890] LNetError: 189367:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7470.457719] LNet: Removed LNI 192.168.203.30@tcp [ 7471.224339] Key type .llcrypt unregistered [ 7471.227115] Key type ._llcrypt unregistered [ 7486.854650] Key type ._llcrypt registered [ 7486.863337] Key type .llcrypt registered [ 7488.268541] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 19:47:57 (1763340477) [ 7509.234795] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7509.252216] alg: No test for adler32 (adler32-zlib) [ 7510.338143] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [ 7510.609622] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 7512.375119] Key type lgssc registered [ 7513.679974] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7583.771518] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7586.722185] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7605.072822] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7641.201780] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 19:50:31 (1763340631) [ 7664.798966] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7668.653111] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7684.573333] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7695.380345] Lustre: Mounted lustre-client [ 7698.699641] LustreError: 195885:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b061e4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7698.718100] LustreError: 195885:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7698.764164] Lustre: Unmounted lustre-client [ 7723.318635] Key type lgssc unregistered [ 7723.557352] LNet: 196287:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7723.573496] LNetError: 196287:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7723.610339] LNet: Removed LNI 192.168.203.30@tcp [ 7724.282748] Key type .llcrypt unregistered [ 7724.284541] Key type ._llcrypt unregistered [ 7734.500933] Key type ._llcrypt registered [ 7734.502737] Key type .llcrypt registered [ 7735.457270] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 7736.755145] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 19:52:06 (1763340726) [ 7750.582753] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7750.605987] alg: No test for adler32 (adler32-zlib) [ 7751.609351] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [ 7751.776856] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 7753.495393] Key type lgssc registered [ 7754.577927] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7828.782459] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7831.124469] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7847.623809] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid [ 7863.556709] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid [ 7866.264681] Lustre: Mounted lustre-client [ 7883.246068] LustreError: 201821:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b10ede800: lov tgt 1000 not cleaned! deathrow=0, lovrc=1 [ 7883.272463] LustreError: 201821:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7883.390340] Lustre: Unmounted lustre-client [ 7995.180607] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7998.911698] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8016.719772] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8052.358691] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 19:57:22 (1763341042) [ 8053.436402] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 8054.793453] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 19:57:24 (1763341044) [ 8094.749366] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8097.407834] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8119.581756] Key type lgssc unregistered [ 8119.790741] LNet: 207998:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8119.797118] LNetError: 207998:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8119.810258] LNet: Removed LNI 192.168.203.30@tcp [ 8120.381502] Key type .llcrypt unregistered [ 8120.383055] Key type ._llcrypt unregistered [ 8133.622168] Key type ._llcrypt registered [ 8133.626775] Key type .llcrypt registered [ 8134.882634] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 19:58:44 (1763341124) [ 8159.456933] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8159.488797] alg: No test for adler32 (adler32-zlib) [ 8160.522246] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [ 8160.673225] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 8162.327144] Key type lgssc registered [ 8163.265486] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8196.011283] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8198.755131] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8212.233527] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8229.807216] Key type lgssc unregistered [ 8230.038702] LNet: 211629:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8230.043700] LNetError: 211629:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8230.053739] LNet: Removed LNI 192.168.203.30@tcp [ 8230.516501] Key type .llcrypt unregistered [ 8230.519048] Key type ._llcrypt unregistered [ 8242.524137] Key type ._llcrypt registered [ 8242.525523] Key type .llcrypt registered [ 8243.819512] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 20:00:33 (1763341233) [ 8245.861118] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8245.870668] alg: No test for adler32 (adler32-zlib) [ 8246.880160] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [ 8247.096359] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 8248.775180] Key type lgssc registered [ 8249.732460] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8287.567257] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8290.614090] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8304.030500] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8314.964127] Lustre: Mounted lustre-client [ 8325.097351] Lustre: lustre-MDT0000-mdc-ffff8e2b05e24800: Connection to lustre-MDT0000 (at 192.168.203.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8330.228732] Lustre: lustre-MDT0001-mdc-ffff8e2b05e24800: Connection to lustre-MDT0001 (at 192.168.203.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8346.591378] Lustre: 212616:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763341321/real 1763341321] req@0000000006729860 x1848997302059776/t0(0) o400->MGC192.168.203.130@tcp@192.168.203.130@tcp:26/25 lens 224/224 e 0 to 1 dl 1763341337 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 8346.605970] LustreError: MGC192.168.203.130@tcp: Connection to MGS (at 192.168.203.130@tcp) was lost; in progress operations using this service will fail [ 8356.780739] Lustre: Evicted from MGS (at 192.168.203.130@tcp) after server handle changed from 0xf4cd5e69bc95de6d to 0xf4cd5e69bc95facf [ 8356.802851] Lustre: MGC192.168.203.130@tcp: Connection restored to 192.168.203.130@tcp (at 192.168.203.130@tcp) [ 8375.270142] LustreError: lustre-MDT0000-mdc-ffff8e2b05e24800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 8375.326422] Lustre: lustre-MDT0000-mdc-ffff8e2b05e24800: Connection restored to 192.168.203.130@tcp (at 192.168.203.130@tcp) [ 8375.957848] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8377.373438] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 8380.554236] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8382.243448] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 8384.721677] LustreError: 216050:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b05e24800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8384.729870] LustreError: 216050:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8384.779967] Lustre: Unmounted lustre-client [ 8410.658223] Key type lgssc unregistered [ 8410.886715] LNet: 216453:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8410.897755] LNetError: 216453:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8410.920465] LNet: Removed LNI 192.168.203.30@tcp [ 8411.544082] Key type .llcrypt unregistered [ 8411.545737] Key type ._llcrypt unregistered [ 8425.544615] Key type ._llcrypt registered [ 8425.546124] Key type .llcrypt registered [ 8426.744893] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 20:03:36 (1763341416) [ 8428.946650] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8428.991230] alg: No test for adler32 (adler32-zlib) [ 8430.068299] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [ 8430.342849] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 8432.055474] Key type lgssc registered [ 8433.278556] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8473.199338] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8476.643350] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8524.424477] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8528.006217] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8552.357860] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8570.089291] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 8606.241680] Key type lgssc unregistered [ 8606.522553] LNet: 221562:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8606.537633] LNetError: 221562:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8606.566457] LNet: Removed LNI 192.168.203.30@tcp [ 8607.344675] Key type .llcrypt unregistered [ 8607.354625] Key type ._llcrypt unregistered [ 8645.576658] Key type ._llcrypt registered [ 8645.589074] Key type .llcrypt registered [ 8646.075954] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8646.101185] alg: No test for adler32 (adler32-zlib) [ 8647.136423] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [ 8647.363379] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 8649.047167] Key type lgssc registered [ 8650.273358] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8718.445481] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8721.574626] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8739.481110] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8775.536531] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 20:09:25 (1763341765) [ 8871.666522] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8875.137259] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8897.447963] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8935.070473] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 20:12:04 (1763341924) [ 8942.356922] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 20:12:12 (1763341932) [ 8969.863636] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8972.412518] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8986.739258] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8997.386678] Lustre: Mounted lustre-client [ 9001.355734] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [ 9002.853089] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 9004.020133] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 9005.271916] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [ 9007.595137] Lustre: lustre-MDT0000-mdc-ffff8e2b07527800: Connection to lustre-MDT0000 (at 192.168.203.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9022.943869] Lustre: 222348:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763341998/real 1763341998] req@0000000069619adc x1848997721417472/t0(0) o400->MGC192.168.203.130@tcp@192.168.203.130@tcp:26/25 lens 224/224 e 0 to 1 dl 1763342014 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9022.954277] LustreError: MGC192.168.203.130@tcp: Connection to MGS (at 192.168.203.130@tcp) was lost; in progress operations using this service will fail [ 9023.008793] Lustre: Evicted from MGS (at 192.168.203.130@tcp) after server handle changed from 0x4d144a3bec882734 to 0x4d144a3bec8829a3 [ 9023.022914] Lustre: MGC192.168.203.130@tcp: Connection restored to 192.168.203.130@tcp (at 192.168.203.130@tcp) [ 9027.880670] Lustre: lustre-MDT0000-mdc-ffff8e2b07527800: Connection restored to 192.168.203.130@tcp (at 192.168.203.130@tcp) [ 9031.812435] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9033.120340] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9035.829124] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [ 9038.323685] Lustre: lustre-MDT0000-mdc-ffff8e2b07527800: Connection to lustre-MDT0000 (at 192.168.203.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9054.687212] Lustre: 222347:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763342029/real 1763342029] req@00000000af8ec18f x1848997721424384/t0(0) o400->MGC192.168.203.130@tcp@192.168.203.130@tcp:26/25 lens 224/224 e 0 to 1 dl 1763342045 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9054.731413] LustreError: MGC192.168.203.130@tcp: Connection to MGS (at 192.168.203.130@tcp) was lost; in progress operations using this service will fail [ 9060.618022] Lustre: lustre-MDT0000-mdc-ffff8e2b07527800: Connection restored to 192.168.203.130@tcp (at 192.168.203.130@tcp) [ 9063.996547] Lustre: Evicted from MGS (at 192.168.203.130@tcp) after server handle changed from 0x4d144a3bec8829a3 to 0x4d144a3bec882e42 [ 9064.008761] Lustre: MGC192.168.203.130@tcp: Connection restored to 192.168.203.130@tcp (at 192.168.203.130@tcp) [ 9064.960657] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9066.328126] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9069.040142] LustreError: 235190:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b07527800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9069.062822] LustreError: 235190:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9069.129581] Lustre: Unmounted lustre-client [ 9125.502188] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9129.229270] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9145.725916] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9156.652564] Lustre: Mounted lustre-client [ 9158.041200] LustreError: 236812:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b082e8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9158.072333] LustreError: 236812:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9158.082073] LustreError: 236812:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 9158.164079] Lustre: Unmounted lustre-client [ 9190.710583] Key type lgssc unregistered [ 9190.938663] LNet: 237215:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9190.944917] LNetError: 237215:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9190.961217] LNet: Removed LNI 192.168.203.30@tcp [ 9191.360492] Key type .llcrypt unregistered [ 9191.362301] Key type ._llcrypt unregistered [ 9204.151946] Key type ._llcrypt registered [ 9204.155244] Key type .llcrypt registered [ 9205.472027] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 20:16:35 (1763342195) [ 9210.437361] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9210.499164] alg: No test for adler32 (adler32-zlib) [ 9211.661739] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [ 9211.880565] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 9213.551166] Key type lgssc registered [ 9214.740428] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9254.151284] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9257.535769] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9274.259402] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9285.178454] Lustre: Mounted lustre-client [ 9289.792416] LustreError: 240386:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b11243000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9289.821284] LustreError: 240386:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9289.861310] Lustre: Unmounted lustre-client [ 9342.977762] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9346.228533] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9362.151039] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9368.111346] Lustre: Mounted lustre-client [ 9375.744584] LustreError: 241984:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b048d6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9375.764107] LustreError: 241984:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9375.766990] LustreError: 241984:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 9375.823443] Lustre: Unmounted lustre-client [ 9430.732822] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9433.833154] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9449.491702] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9460.792811] Lustre: Mounted lustre-client [ 9461.612096] LustreError: 243603:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b0a2b4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9461.637293] LustreError: 243603:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9461.641063] LustreError: 243603:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 9461.695219] Lustre: Unmounted lustre-client [ 9486.912395] Key type lgssc unregistered [ 9487.128285] LNet: 244006:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9487.145310] LNetError: 244006:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9487.156843] LNet: Removed LNI 192.168.203.30@tcp [ 9487.681443] Key type .llcrypt unregistered [ 9487.684825] Key type ._llcrypt unregistered [ 9500.567178] Key type ._llcrypt registered [ 9500.571553] Key type .llcrypt registered [ 9501.699422] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 20:21:31 (1763342491) [ 9504.380903] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9504.421761] alg: No test for adler32 (adler32-zlib) [ 9505.436455] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [ 9505.608239] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 9507.263188] Key type lgssc registered [ 9508.229825] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9544.762622] Key type lgssc unregistered [ 9544.971995] LNet: 246320:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9544.983263] LNetError: 246320:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9545.014633] LNet: Removed LNI 192.168.203.30@tcp [ 9545.583239] Key type .llcrypt unregistered [ 9545.585238] Key type ._llcrypt unregistered [ 9568.916595] Key type ._llcrypt registered [ 9568.918232] Key type .llcrypt registered [ 9569.511790] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9569.521872] alg: No test for adler32 (adler32-zlib) [ 9570.560179] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [ 9570.776813] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 9572.463896] Key type lgssc registered [ 9573.382575] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9645.575798] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9649.300210] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9669.923406] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9714.201354] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [ 9715.775483] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 20:25:05 (1763342705) [ 9741.358680] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9745.124699] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9762.747472] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9780.888689] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 9782.435090] Lustre: Mounted lustre-client [ 9787.894268] Lustre: lustre-OST0001-osc-ffff8e2b05134800: Connection to lustre-OST0001 (at 192.168.203.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9793.619433] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 9803.889644] LustreError: 252824:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 9803.941665] Lustre: Unmounted lustre-client [ 9845.577314] Key type lgssc unregistered [ 9845.824664] LNet: 253229:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9845.830981] LNetError: 253229:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9845.850158] LNet: Removed LNI 192.168.203.30@tcp [ 9846.485399] Key type .llcrypt unregistered [ 9846.491589] Key type ._llcrypt unregistered [ 9885.994959] Key type ._llcrypt registered [ 9885.996817] Key type .llcrypt registered [ 9886.496603] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9886.521318] alg: No test for adler32 (adler32-zlib) [ 9887.653239] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [ 9887.846761] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [ 9889.495166] Key type lgssc registered [ 9890.803161] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9956.591211] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9960.048687] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9977.798439] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10031.518937] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 20:30:21 (1763343021) [10067.976359] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10071.480813] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10091.420044] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 20:31:21 (1763343081) [10115.510067] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10118.691878] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10134.619833] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10145.842668] Lustre: Mounted lustre-client [10162.693341] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [10165.909965] LustreError: 261078:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [10166.005703] Lustre: Unmounted lustre-client [10259.845502] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10264.198285] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10305.091170] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10305.811926] Lustre: Mounted lustre-client [10307.286843] LustreError: 263095:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b07522800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10307.304900] LustreError: 263095:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [10307.310636] LustreError: 263095:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [10307.374171] Lustre: Unmounted lustre-client [10331.958988] Key type lgssc unregistered [10332.185252] LNet: 263497:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10332.195299] LNetError: 263497:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10332.215622] LNet: Removed LNI 192.168.203.30@tcp [10332.738367] Key type .llcrypt unregistered [10332.740138] Key type ._llcrypt unregistered [10352.773894] Key type ._llcrypt registered [10352.775264] Key type .llcrypt registered [10353.152120] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10353.164743] alg: No test for adler32 (adler32-zlib) [10354.186282] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [10354.385656] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [10356.045103] Key type lgssc registered [10357.188989] Lustre: Echo OBD driver; http://www.lustre.org/ [10422.435423] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10425.588634] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10443.856291] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10488.002100] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [10489.504247] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 20:37:59 (1763343479) [10515.389095] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10519.069879] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10535.271719] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10543.735727] Lustre: Mounted lustre-client [10546.395143] LustreError: 269546:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b048d1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10546.423740] LustreError: 269546:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [10546.488944] Lustre: Unmounted lustre-client [10580.808616] Key type lgssc unregistered [10581.094111] LNet: 269951:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10581.103870] LNetError: 269951:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10581.128331] LNet: Removed LNI 192.168.203.30@tcp [10581.687177] Key type .llcrypt unregistered [10581.690049] Key type ._llcrypt unregistered [10594.961357] Key type ._llcrypt registered [10594.963037] Key type .llcrypt registered [10596.190340] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [10597.631067] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 20:39:47 (1763343587) [10605.276509] Key type .llcrypt unregistered [10605.278272] Key type ._llcrypt unregistered [10612.118730] Key type ._llcrypt registered [10612.121222] Key type .llcrypt registered [10612.358381] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10612.382496] alg: No test for adler32 (adler32-zlib) [10613.426239] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [10613.672288] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [10615.328074] Key type lgssc registered [10616.372403] Lustre: Echo OBD driver; http://www.lustre.org/ [10664.296286] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10677.488361] Lustre: Mounted lustre-client [10678.881578] LustreError: 272967:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b05ecb800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10678.912983] LustreError: 272967:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [10678.959599] Lustre: Unmounted lustre-client [10697.072655] Key type lgssc unregistered [10697.305176] LNet: 273369:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10697.310931] LNetError: 273369:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10697.342610] LNet: Removed LNI 192.168.203.30@tcp [10697.970839] Key type .llcrypt unregistered [10697.973728] Key type ._llcrypt unregistered [10710.631618] Key type ._llcrypt registered [10710.632865] Key type .llcrypt registered [10711.754887] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 20:41:41 (1763343701) [10713.391380] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10713.399672] alg: No test for adler32 (adler32-zlib) [10714.370896] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [10714.559804] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [10716.223202] Key type lgssc registered [10717.040819] Lustre: Echo OBD driver; http://www.lustre.org/ [10790.546457] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10812.795974] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10815.626979] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10817.213139] Lustre: Mounted lustre-client [10818.836615] LustreError: 276412:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b0665a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10818.862819] LustreError: 276412:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [10818.903295] Lustre: Unmounted lustre-client [10850.877082] Key type lgssc unregistered [10851.117663] LNet: 276815:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10851.131410] LNetError: 276815:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10852.205592] LNet: Removed LNI 192.168.203.30@tcp [10852.804393] Key type .llcrypt unregistered [10852.809933] Key type ._llcrypt unregistered [10865.788148] Key type ._llcrypt registered [10865.789893] Key type .llcrypt registered [10867.096316] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 20:44:16 (1763343856) [10869.130943] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10869.228179] alg: No test for adler32 (adler32-zlib) [10870.352415] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [10870.606125] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [10872.263415] Key type lgssc registered [10873.410298] Lustre: Echo OBD driver; http://www.lustre.org/ [10910.703120] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10913.639384] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10928.588758] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10931.341228] Lustre: Mounted lustre-client [10936.298164] Lustre: lustre-MDT0000-mdc-ffff8e2b082ed800: Connection to lustre-MDT0000 (at 192.168.203.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [10941.481371] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [10941.514715] LustreError: 279885:0:(file.c:6099:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [10941.787863] LustreError: 279904:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b082ed800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10941.796670] LustreError: MGC192.168.203.130@tcp: Connection to MGS (at 192.168.203.130@tcp) was lost; in progress operations using this service will fail [10941.804559] LustreError: 279904:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [10941.850387] Lustre: Unmounted lustre-client [10960.021982] Key type lgssc unregistered [10960.282054] LNet: 280296:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10960.291123] LNetError: 280296:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10961.319901] LNet: Removed LNI 192.168.203.30@tcp [10961.793065] Key type .llcrypt unregistered [10961.794285] Key type ._llcrypt unregistered [10973.910702] Key type ._llcrypt registered [10973.912139] Key type .llcrypt registered [10974.976696] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 20:46:04 (1763343964) [10976.562428] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10976.590661] alg: No test for adler32 (adler32-zlib) [10977.601170] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [10977.781389] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [10979.399180] Key type lgssc registered [10980.352728] Lustre: Echo OBD driver; http://www.lustre.org/ [11018.513181] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11021.956673] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11038.964968] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11044.913654] Lustre: Mounted lustre-client [11049.964906] Lustre: lustre-MDT0001-mdc-ffff8e2b07525000: Connection to lustre-MDT0001 (at 192.168.203.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [11054.772861] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [11055.962900] LustreError: 283390:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b07525000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11055.982544] LustreError: 283390:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [11056.063112] Lustre: Unmounted lustre-client [11072.593279] Key type lgssc unregistered [11072.838804] LNet: 283782:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11072.849542] LNetError: 283782:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11072.871971] LNet: Removed LNI 192.168.203.30@tcp [11073.526612] Key type .llcrypt unregistered [11073.529746] Key type ._llcrypt unregistered [11084.867580] Key type ._llcrypt registered [11084.869757] Key type .llcrypt registered [11086.096126] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 20:47:55 (1763344075) [11101.578983] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11101.601663] alg: No test for adler32 (adler32-zlib) [11102.690933] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [11102.872462] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [11104.559226] Key type lgssc registered [11105.683747] Lustre: Echo OBD driver; http://www.lustre.org/ [11200.107346] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11201.788224] Lustre: Mounted lustre-client [11233.261659] Lustre: lustre-MDT0001-mdc-ffff8e2b05d9b800: Connection to lustre-MDT0001 (at 192.168.203.130@tcp) was lost; in progress operations using this service will wait for recovery to complete [11240.380291] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11250.677698] LustreError: 289129:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [11250.726498] Lustre: Unmounted lustre-client [11273.814512] Key type lgssc unregistered [11274.051316] LNet: 289530:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11274.058832] LNetError: 289530:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11274.075409] LNet: Removed LNI 192.168.203.30@tcp [11274.703344] Key type .llcrypt unregistered [11274.704891] Key type ._llcrypt unregistered [11286.934966] Key type ._llcrypt registered [11286.936786] Key type .llcrypt registered [11287.987779] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 20:51:17 (1763344277) [11289.298757] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [11290.541778] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 20:51:20 (1763344280) [11291.761527] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [11293.120392] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 20:51:23 (1763344283) [11294.398847] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [11296.153374] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 20:51:26 (1763344286) [11297.635797] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [11299.121978] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 20:51:29 (1763344289) [11300.490346] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [11301.985260] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 20:51:31 (1763344291) [11309.750400] Key type .llcrypt unregistered [11309.751924] Key type ._llcrypt unregistered [11315.740121] Key type ._llcrypt registered [11315.747843] Key type .llcrypt registered [11316.256972] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11316.281193] alg: No test for adler32 (adler32-zlib) [11317.437869] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [11317.699895] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [11319.415184] Key type lgssc registered [11320.641662] Lustre: Echo OBD driver; http://www.lustre.org/ [11381.616616] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11385.128181] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11403.752504] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11405.414547] Lustre: Mounted lustre-client [11407.323277] LustreError: 295120:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b05131000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11407.342921] LustreError: 295120:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [11407.408049] Lustre: Unmounted lustre-client [11444.552488] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 20:53:54 (1763344434) [11470.945467] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11474.169810] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11491.065591] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11502.133430] Lustre: Mounted lustre-client [11503.528616] LustreError: 297234:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b18198000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11503.557981] LustreError: 297234:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [11503.561939] LustreError: 297234:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [11503.631479] Lustre: Unmounted lustre-client [11533.535757] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 20:55:23 (1763344523) [11543.007423] LNet: 1 local NIs in recovery (showing 1): 192.168.203.30@tcp [11600.669340] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11603.887162] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11619.563334] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11701.621870] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 20:58:11 (1763344691) [11712.496926] Key type lgssc unregistered [11712.805260] LNet: 302687:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11712.814919] LNetError: 302687:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11712.835497] LNet: Removed LNI 192.168.203.30@tcp [11713.453879] Key type .llcrypt unregistered [11713.455675] Key type ._llcrypt unregistered [11755.490061] Key type ._llcrypt registered [11755.505747] Key type .llcrypt registered [11755.857473] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11755.868343] alg: No test for adler32 (adler32-zlib) [11756.903722] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [11757.129501] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [11758.807177] Key type lgssc registered [11760.317562] Lustre: Echo OBD driver; http://www.lustre.org/ [11829.548094] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [11832.192801] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [11850.104440] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11881.461577] Key type lgssc unregistered [11881.655848] LNet: 306286:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11881.667775] LNetError: 306286:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11881.689312] LNet: Removed LNI 192.168.203.30@tcp [11882.268764] Key type .llcrypt unregistered [11882.271522] Key type ._llcrypt unregistered [11917.269267] Key type ._llcrypt registered [11917.271690] Key type .llcrypt registered [11918.272086] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 21:01:48 (1763344908) [11935.785643] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11935.825869] alg: No test for adler32 (adler32-zlib) [11936.915815] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [11937.150115] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [11938.807943] Key type lgssc registered [11939.953875] Lustre: Echo OBD driver; http://www.lustre.org/ [11983.025033] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 21:02:53 (1763344973) [11995.252797] Key type lgssc unregistered [11995.465563] LNet: 309834:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11995.481523] LNetError: 309834:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11995.499860] LNet: Removed LNI 192.168.203.30@tcp [11996.274888] Key type .llcrypt unregistered [11996.276397] Key type ._llcrypt unregistered [12007.355937] Key type ._llcrypt registered [12007.358350] Key type .llcrypt registered [12007.830886] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12007.846123] alg: No test for adler32 (adler32-zlib) [12008.922086] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [12009.191684] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [12010.919837] Key type lgssc registered [12012.095068] Lustre: Echo OBD driver; http://www.lustre.org/ [12062.133933] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12065.683578] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12083.443567] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12307.283743] LNet: Added LNI 192.168.203.30@tcp50 [8/256/0/180] [12309.926980] LNet: Removed LNI 192.168.203.30@tcp [12312.169452] Lustre: 314127:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff8e2b061e0800: config has no valid NIDs, force dynamic NIDs [12313.408679] Lustre: Mounted lustre-client [12313.467408] LustreError: 314143:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b061e0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12313.484347] LustreError: 314143:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [12313.553697] Lustre: Unmounted lustre-client [12343.296831] Key type lgssc unregistered [12343.621889] LNet: 314980:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12343.626153] LNetError: 314980:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12343.648742] LNet: Removed LNI 192.168.203.30@tcp50 [12344.095801] Key type .llcrypt unregistered [12344.097938] Key type ._llcrypt unregistered [12366.706873] Key type ._llcrypt registered [12366.708609] Key type .llcrypt registered [12367.790928] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 21:09:17 (1763345357) [12369.777311] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12369.853293] alg: No test for adler32 (adler32-zlib) [12370.908975] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [12371.139304] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [12372.807743] Key type lgssc registered [12373.726702] Lustre: Echo OBD driver; http://www.lustre.org/ [12410.211445] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12413.260700] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12428.652099] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12440.585423] Lustre: Mounted lustre-client [12482.568635] Lustre: Mounted fs15246-client [12493.711527] LustreError: 319118:0:(lov_obd.c:783:lov_cleanup()) fs15246-clilov-ffff8e2b063e6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12493.725344] LustreError: 319118:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [12493.750872] Lustre: Unmounted fs15246-client [12513.577749] LustreError: 319335:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e2b082e9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12513.590540] LustreError: 319335:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [12513.597962] LustreError: 319335:0:(obd_class.h:479:obd_check_dev()) Skipped 3 previous similar messages [12513.653308] Lustre: Unmounted lustre-client [12546.057483] Key type lgssc unregistered [12546.261924] LNet: 319738:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12546.266872] LNetError: 319738:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12546.282696] LNet: Removed LNI 192.168.203.30@tcp [12546.826667] Key type .llcrypt unregistered [12546.827937] Key type ._llcrypt unregistered [12556.484791] Key type ._llcrypt registered [12556.486020] Key type .llcrypt registered [12557.971693] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 21:12:27 (1763345547) [12559.849139] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12559.896338] alg: No test for adler32 (adler32-zlib) [12561.100607] Lustre: Lustre: Build Version: 2.16.61_51_gca28732 [12561.436535] LNet: Added LNI 192.168.203.30@tcp [8/256/0/180] [12563.223165] Key type lgssc registered [12564.512118] Lustre: Echo OBD driver; http://www.lustre.org/ [12668.027925] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [12671.418416] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [12687.613683] Lustre: DEBUG MARKER: oleg330-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [12776.138274] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 12523 sec ========= 21:16:05 (1763345765) [12777.421840] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 21:16:07 (1763345767) === [12780.441506] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 21:16:10 (1763345770) === [12810.033107] Key type lgssc unregistered [12810.267861] LNet: 328898:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12810.272503] LNetError: 328898:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12810.284139] LNet: Removed LNI 192.168.203.30@tcp [12810.816371] Key type .llcrypt unregistered [12810.820295] Key type ._llcrypt unregistered