[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.17.0-8.fc42 06/10/2025 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 415987511 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2421 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE22BD 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 00227D (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2331 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE23C1 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE23F9 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe22bd-0xbffe2330] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22bc] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2331-0xbffe23c0] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe23c1-0xbffe23f8] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe23f9-0xbffe2420] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 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.001011] APIC: Switch to symmetric I/O mode setup [ 0.003103] x2apic enabled [ 0.004008] Switched APIC routing to physical x2apic. [ 0.005011] kvm-guest: setup PV IPIs [ 0.008649] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.009000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.009024] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.011009] pid_max: default: 32768 minimum: 301 [ 0.012131] LSM: Security Framework initializing [ 0.013060] Yama: becoming mindful. [ 0.014039] SELinux: Initializing. [ 0.015067] *** VALIDATE selinux *** [ 0.023485] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.028008] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.029152] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031039] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.033110] *** VALIDATE tmpfs *** [ 0.034478] *** VALIDATE proc *** [ 0.036058] *** VALIDATE cgroup *** [ 0.037010] *** VALIDATE cgroup2 *** [ 0.038282] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.040024] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.041009] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.042029] Spectre V2 : User space: Vulnerable [ 0.043010] Speculative Store Bypass: Vulnerable [ 0.046388] debug: unmapping init [mem 0xffffffff8f459000-0xffffffff8f460fff] [ 0.049176] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.050660] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.051024] ... version: 2 [ 0.052014] ... bit width: 48 [ 0.053012] ... generic registers: 4 [ 0.054015] ... value mask: 0000ffffffffffff [ 0.055014] ... max period: 00007fffffffffff [ 0.056013] ... fixed-purpose events: 3 [ 0.057011] ... event mask: 000000070000000f [ 0.058319] rcu: Hierarchical SRCU implementation. [ 0.060461] smp: Bringing up secondary CPUs ... [ 0.061607] x86: Booting SMP configuration: [ 0.062027] .... node #0, CPUs: #1 #2 #3 [ 0.065167] smp: Brought up 1 node, 4 CPUs [ 0.067014] smpboot: Max logical packages: 1 [ 0.068018] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.154424] node 0 deferred pages initialised in 84ms [ 0.159120] devtmpfs: initialized [ 0.160271] x86/mm: Memory block size: 128MB [ 0.164460] gcov: version magic: 0x41383552 [ 0.166357] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.170089] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.172316] pinctrl core: initialized pinctrl subsystem [ 0.174196] [ 0.174739] ************************************************************* [ 0.177014] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.179010] ** ** [ 0.181014] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.183012] ** ** [ 0.185014] ** This means that this kernel is built to expose internal ** [ 0.188014] ** IOMMU data structures, which may compromise security on ** [ 0.190013] ** your system. ** [ 0.193014] ** ** [ 0.195013] ** If you see this message and you are not debugging the ** [ 0.197025] ** kernel, report this immediately to your vendor! ** [ 0.199015] ** ** [ 0.202014] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.204012] ************************************************************* [ 0.206358] NET: Registered protocol family 16 [ 0.208447] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.210048] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.213064] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.217043] cpuidle: using governor menu [ 0.218746] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.220555] PCI: Using configuration type 1 for base access [ 0.223114] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.232051] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.233083] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.236055] cryptd: max_cpu_qlen set to 1000 [ 0.238244] ACPI: Added _OSI(Module Device) [ 0.240014] ACPI: Added _OSI(Processor Device) [ 0.241011] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.243014] ACPI: Added _OSI(Processor Aggregator Device) [ 0.248476] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.253353] ACPI: Interpreter enabled [ 0.255060] ACPI: PM: (supports S0 S3 S4 S5) [ 0.256012] ACPI: Using IOAPIC for interrupt routing [ 0.258126] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.261385] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.272754] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.275038] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.277021] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.281112] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.286526] acpiphp: Slot [2] registered [ 0.288075] acpiphp: Slot [5] registered [ 0.289128] acpiphp: Slot [6] registered [ 0.291163] acpiphp: Slot [3] registered [ 0.292170] acpiphp: Slot [4] registered [ 0.294105] acpiphp: Slot [7] registered [ 0.295090] acpiphp: Slot [8] registered [ 0.296123] acpiphp: Slot [9] registered [ 0.298106] acpiphp: Slot [10] registered [ 0.299205] acpiphp: Slot [11] registered [ 0.301184] acpiphp: Slot [12] registered [ 0.302118] acpiphp: Slot [13] registered [ 0.304184] acpiphp: Slot [14] registered [ 0.305106] acpiphp: Slot [15] registered [ 0.306066] acpiphp: Slot [16] registered [ 0.307086] acpiphp: Slot [17] registered [ 0.309086] acpiphp: Slot [18] registered [ 0.310065] acpiphp: Slot [19] registered [ 0.311064] acpiphp: Slot [20] registered [ 0.312078] acpiphp: Slot [21] registered [ 0.313034] acpiphp: Slot [22] registered [ 0.314011] acpiphp: Slot [23] registered [ 0.315101] acpiphp: Slot [24] registered [ 0.316097] acpiphp: Slot [25] registered [ 0.318094] acpiphp: Slot [26] registered [ 0.319091] acpiphp: Slot [27] registered [ 0.320096] acpiphp: Slot [28] registered [ 0.321124] acpiphp: Slot [29] registered [ 0.323186] acpiphp: Slot [30] registered [ 0.325125] acpiphp: Slot [31] registered [ 0.326041] PCI host bridge to bus 0000:00 [ 0.327013] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.328017] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.330029] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.333026] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.335032] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.338025] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.340171] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.342881] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.346314] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.353015] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.357103] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.360019] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.362024] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.364028] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.366744] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.368739] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.371046] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.373712] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.375857] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.385017] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.388062] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.394250] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.401017] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.407017] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.426021] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.432446] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.438018] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.444021] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.455017] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.467793] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.470376] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.472354] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.474371] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.477313] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.481271] iommu: Default domain type: Passthrough [ 0.482520] SCSI subsystem initialized [ 0.483115] ACPI: bus type USB registered [ 0.484146] usbcore: registered new interface driver usbfs [ 0.485053] usbcore: registered new interface driver hub [ 0.486058] usbcore: registered new device driver usb [ 0.487143] pps_core: LinuxPPS API ver. 1 registered [ 0.489011] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.491060] PTP clock support registered [ 0.493134] EDAC MC: Ver: 3.0.0 [ 0.495084] PCI: Using ACPI for IRQ routing [ 0.496672] NetLabel: Initializing [ 0.498011] NetLabel: domain hash size = 128 [ 0.499008] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.501072] NetLabel: unlabeled traffic allowed by default [ 0.502116] vgaarb: loaded [ 0.503309] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.505009] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.510031] clocksource: Switched to clocksource kvm-clock [ 0.602992] VFS: Disk quotas dquot_6.6.0 [ 0.604090] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.605785] *** VALIDATE ramfs *** [ 0.606491] *** VALIDATE hugetlbfs *** [ 0.607541] pnp: PnP ACPI init [ 0.609449] pnp: PnP ACPI: found 6 devices [ 0.626263] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.629711] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.631709] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.633383] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.635209] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.637450] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.640368] NET: Registered protocol family 2 [ 0.642917] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.647701] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.651376] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.656495] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.659904] TCP: Hash tables configured (established 65536 bind 65536) [ 0.662867] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.666408] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.669738] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.672896] NET: Registered protocol family 1 [ 0.676347] RPC: Registered named UNIX socket transport module. [ 0.678413] RPC: Registered udp transport module. [ 0.679773] RPC: Registered tcp transport module. [ 0.681116] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.682975] NET: Registered protocol family 44 [ 0.684759] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.686673] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.689061] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.691224] PCI: CLS 0 bytes, default 64 [ 0.692629] Unpacking initramfs... [ 2.029441] debug: unmapping init [mem 0xffff9f0d7cc64000-0xffff9f0d7ffcffff] [ 2.031950] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.033346] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.034908] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.516497] Initialise system trusted keyrings [ 2.518087] Key type blacklist registered [ 2.520401] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.526693] zbud: loaded [ 2.529468] *** VALIDATE nfs *** [ 2.530658] *** VALIDATE nfs4 *** [ 2.533098] pstore: using deflate compression [ 2.538991] Platform Keyring initialized [ 2.639996] NET: Registered protocol family 38 [ 2.641995] Key type asymmetric registered [ 2.643762] Asymmetric key parser 'x509' registered [ 2.645366] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.648893] io scheduler mq-deadline registered [ 2.650794] io scheduler kyber registered [ 2.652813] io scheduler bfq registered [ 2.654996] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.658274] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.660877] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.663397] ACPI: Power Button [PWRF] [ 2.668479] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.678836] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.687886] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.715504] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.740238] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.744906] Non-volatile memory driver v1.3 [ 2.745856] Linux agpgart interface v0.103 [ 2.776485] virtio_blk virtio1: [vda] 139368 512-byte logical blocks (71.4 MB/68.1 MiB) [ 2.779884] vda: detected capacity change from 0 to 71356416 [ 2.793653] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.796540] vdb: detected capacity change from 0 to 1073741824 [ 2.802311] libphy: Fixed MDIO Bus: probed [ 2.808464] usbcore: registered new interface driver usbserial_generic [ 2.811191] usbserial: USB Serial support registered for generic [ 2.813770] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.817977] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.819962] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.822395] mousedev: PS/2 mouse device common for all mice [ 2.824436] rtc_cmos 00:05: RTC can wake from S4 [ 2.826315] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.828878] rtc_cmos 00:05: registered as rtc0 [ 2.831317] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.831597] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 2.834416] intel_pstate: CPU model not supported [ 2.838779] hid: raw HID events driver (C) Jiri Kosina [ 2.841395] usbcore: registered new interface driver usbhid [ 2.842325] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 2.843326] usbhid: USB HID core driver [ 2.847843] drop_monitor: Initializing network drop monitor service [ 2.849590] Initializing XFRM netlink socket [ 2.851032] NET: Registered protocol family 10 [ 2.853751] Segment Routing with IPv6 [ 2.855094] NET: Registered protocol family 17 [ 2.857201] mpls_gso: MPLS GSO support [ 2.863368] RAS: Correctable Errors collector initialized. [ 2.865656] AVX version of gcm_enc/dec engaged. [ 2.867421] AES CTR mode by8 optimization enabled [ 2.931509] sched_clock: Marking stable (2931474666, 0)->(3813046356, -881571690) [ 2.934954] registered taskstats version 1 [ 2.937770] Loading compiled-in X.509 certificates [ 2.939416] zswap: loaded using pool lzo/zbud [ 2.960729] Key type big_key registered [ 2.973775] Key type encrypted registered [ 2.975188] ima: No TPM chip found, activating TPM-bypass! [ 2.976607] ima: Allocated hash algorithm: sha1 [ 2.977808] ima: No architecture policies found [ 2.979129] evm: Initialising EVM extended attributes: [ 2.980679] evm: security.selinux [ 2.981988] evm: security.ima [ 2.983972] evm: security.capability [ 2.985835] evm: HMAC attrs: 0x1 [ 2.988476] rtc_cmos 00:05: setting system clock to 2026-02-10 00:47:22 UTC (1770684442) [ 2.995314] debug: unmapping init [mem 0xffffffff90403000-0xffffffff905fffff] [ 2.998852] debug: unmapping init [mem 0xffffffff8f182000-0xffffffff8f458fff] [ 3.008117] Write protecting the kernel read-only data: 28672k [ 3.010867] debug: unmapping init [mem 0xffffffff8d803000-0xffffffff8d9fffff] [ 3.013104] debug: unmapping init [mem 0xffffffff8e114000-0xffffffff8e1fffff] [ 3.037296] 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.044561] systemd[1]: Detected virtualization kvm. [ 3.046433] systemd[1]: Detected architecture x86-64. [ 3.048237] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.075825] systemd[1]: No hostname configured. [ 3.076871] systemd[1]: Set hostname to . [ 3.078387] random: systemd: uninitialized urandom read (16 bytes read) [ 3.080028] systemd[1]: Initializing machine ID from random generator. [ 3.182785] random: systemd: uninitialized urandom read (16 bytes read) [ 3.185695] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 3.189843] random: systemd: uninitialized urandom read (16 bytes read) [ 3.191222] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 3.194764] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Timers. [ OK ] Listening on Journal Socket. Starting Apply Kernel Variables... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Slices. [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Sockets. Starting Journal Service... Starting Setup Virtual Console... [ OK ] Started Memstrack Anylazing Service. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Setup Virtual Console. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 3.690884] device-mapper: uevent: version 1.0.3 [ 3.692227] 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.354318] virtio_net virtio0 ens2: renamed from eth0 [ 4.355500] scsi host0: ata_piix [ 4.369450] scsi host1: ata_piix [ 4.370502] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.373042] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 7.991267] dracut-initqueue[584]: RTNETLINK answers: File exists [ 9.492457] random: crng init done [ 9.493814] 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. [ 9.730223] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Timers. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 10.902210] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.180580] SELinux: Disabled at runtime. [ 11.243120] 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.249150] systemd[1]: Detected virtualization kvm. [ 11.250434] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 11.795861] systemd[1]: initrd-switch-root.service: Succeeded. [ 11.799304] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 11.804641] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 11.808763] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 11.811643] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 11.819304] systemd[1]: Starting Journal Service... Starting Journal Service... [ 11.823802] systemd[1]: proc-sys-fs-binfmt_misc.automount: Refusing to start, unit to trigger not loaded. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Listening on udev Kernel Socket. [ OK ] Stopped target Switch Root. Mounting Kernel Debug File System... Activating swap /dev/disk/by-label/SWAP... [ OK ] Created slice User and Session Slice. [ OK ] Created slice system-serial\x2dgetty.slice. [ 11.879837] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Listening on Process Core Dump Socket. [ OK ] Stopped target Initrd File Systems. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Listening on udev Control Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Slices. Starting Apply Kernel Variables... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Created slice system-sshd\x2dkeygen.slice. Mounting Huge Pages File System... [ OK ] Listening on initctl Compatibility Named Pipe. Mounting POSIX Message Queue File System... [ OK ] Created slice system-getty.slice. Starting Remount Root and Kernel File Systems... Starting udev Coldplug all Devices... [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Reached target rpc_pipefs.target. [ OK ] Stopped target Initrd Root File System. [ OK ] Started Journal Service. [ 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 ] Mounted Huge Pages File System. [ OK ] Mounted POSIX Message Queue File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Mounted /mnt. [ 12.324067] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 12.680344] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 12.710626] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 12.845920] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 12.856426] EDAC sbridge: Ver: 1.1.2 [ 14.097354] Key type dns_resolver registered [ 14.399727] NFS: Registering the id_resolver key type [ 14.401758] Key type id_resolver registered [ 14.403444] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started dnf makecache --timer. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Restore /run/initramfs on shutdown... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started irqbalance daemon. Starting Login Service... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg327-client login: [ 47.536947] libcfs: loading out-of-tree module taints kernel. [ 47.734297] Key type ._llcrypt registered [ 47.737138] Key type .llcrypt registered [ 48.864558] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 48.878439] alg: No test for adler32 (adler32-zlib) [ 50.598137] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 52.053966] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 53.927982] Key type lgssc registered [ 56.148204] Lustre: Echo OBD driver; http://www.lustre.org/ [ 223.002177] hrtimer: interrupt took 27078466 ns [ 240.939972] Lustre: Mounted lustre-client [ 248.177514] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 266.720409] Lustre: lustre-OST0000-osc-ffff9f0dda751800: disconnect after 23s idle [ 268.366896] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing check_logdir /tmp/testlogs/ [ 274.533281] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing yml_node [ 281.501449] Lustre: DEBUG MARKER: Client: 2.17.50.65 [ 284.957179] Lustre: DEBUG MARKER: MDS: 2.17.50.65 [ 288.733462] Lustre: DEBUG MARKER: OSS: 2.17.50.65 [ 290.540392] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Mon Feb 9 19:52:08 EST 2026 [ 315.530959] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 317.261852] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 320.121940] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 330.414989] LustreError: 8995:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 330.506803] Lustre: Unmounted lustre-client [ 449.779939] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 454.165683] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 478.172412] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 519.425428] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 19:55:57 (1770684957) [ 522.507761] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 524.701389] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 19:56:02 (1770684962) [ 557.312299] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 562.001843] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 580.254287] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 580.966796] Lustre: Mounted lustre-client [ 584.877221] LustreError: 14450:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0de0d8b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 584.903769] LustreError: 14450:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 584.907228] LustreError: 14450:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 584.956503] Lustre: Unmounted lustre-client [ 621.818702] Key type lgssc unregistered [ 622.185278] LNet: 14858:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 622.205895] LNetError: 14858:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 622.239620] LNet: Removed LNI 192.168.203.27@tcp [ 623.487316] Key type .llcrypt unregistered [ 623.489596] Key type ._llcrypt unregistered [ 640.623965] Key type ._llcrypt registered [ 640.626397] Key type .llcrypt registered [ 642.219777] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 19:57:59 (1770685079) [ 644.139369] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 644.192261] alg: No test for adler32 (adler32-zlib) [ 645.198617] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 645.393625] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 647.071253] Key type lgssc registered [ 648.009483] Lustre: Echo OBD driver; http://www.lustre.org/ [ 723.855759] Lustre: Mounted lustre-client [ 809.128504] LustreError: 18246:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0dd86ec800: lov tgt 1990 not cleaned! deathrow=0, lovrc=1 [ 809.147268] LustreError: 18246:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 809.208637] Lustre: Unmounted lustre-client [ 847.760503] Key type lgssc unregistered [ 848.068733] LNet: 18725:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 848.083296] LNetError: 18725:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 849.128286] LNet: Removed LNI 192.168.203.27@tcp [ 849.924514] Key type .llcrypt unregistered [ 849.927181] Key type ._llcrypt unregistered [ 859.967707] Key type ._llcrypt registered [ 859.971950] Key type .llcrypt registered [ 861.292816] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 20:01:39 (1770685299) [ 863.892223] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 863.943112] alg: No test for adler32 (adler32-zlib) [ 865.302547] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 865.749277] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 867.511923] Key type lgssc registered [ 869.066264] Lustre: Echo OBD driver; http://www.lustre.org/ [ 911.854156] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 915.492992] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 933.779598] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 940.582385] Lustre: Mounted lustre-client [ 950.862057] LustreError: 21833:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0dd86eb800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 950.897786] LustreError: 21833:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 950.946948] Lustre: Unmounted lustre-client [ 977.076160] Key type lgssc unregistered [ 977.331980] LNet: 22235:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 977.338281] LNetError: 22235:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 977.365306] LNet: Removed LNI 192.168.203.27@tcp [ 977.937385] Key type .llcrypt unregistered [ 977.943335] Key type ._llcrypt unregistered [ 987.000965] Key type ._llcrypt registered [ 987.004707] Key type .llcrypt registered [ 988.129834] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 20:03:46 (1770685426) [ 990.502951] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 990.528340] alg: No test for adler32 (adler32-zlib) [ 991.635342] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 991.905415] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 993.608742] Key type lgssc registered [ 994.558944] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1039.553403] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1043.509401] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1060.709914] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1067.592602] Lustre: Mounted lustre-client [ 1072.690408] LustreError: 24953:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0dd2852000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1072.711074] LustreError: 24953:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1072.750128] Lustre: Unmounted lustre-client [ 1099.533951] Key type lgssc unregistered [ 1099.746361] LNet: 25356:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1099.751277] LNetError: 25356:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1100.793157] LNet: Removed LNI 192.168.203.27@tcp [ 1101.340217] Key type .llcrypt unregistered [ 1101.343274] Key type ._llcrypt unregistered [ 1114.185535] Key type ._llcrypt registered [ 1114.187790] Key type .llcrypt registered [ 1115.444120] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 20:05:53 (1770685553) [ 1117.046823] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1117.258250] alg: No test for adler32 (adler32-zlib) [ 1118.331588] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 1118.577700] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 1120.264727] Key type lgssc registered [ 1121.499754] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1168.292615] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1172.078813] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1188.899751] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1194.516164] Lustre: Mounted lustre-client [ 1199.592757] Lustre: lustre-MDT0000-mdc-ffff9f0dda750000: Connection to lustre-MDT0000 (at 192.168.203.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1209.862416] Lustre: lustre-MDT0001-mdc-ffff9f0dda750000: Connection to lustre-MDT0001 (at 192.168.203.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1215.523470] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1221.089275] Lustre: 26316:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1770685644/real 1770685644] req@000000006711554c x1856698379671168/t0(0) o400->MGC192.168.203.127@tcp@192.168.203.127@tcp:26/25 lens 224/224 e 0 to 1 dl 1770685660 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1221.133247] LustreError: MGC192.168.203.127@tcp: Connection to MGS (at 192.168.203.127@tcp) was lost; in progress operations using this service will fail [ 1225.845772] LustreError: 28537:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1225.913726] Lustre: Unmounted lustre-client [ 1243.128689] Key type lgssc unregistered [ 1243.355075] LNet: 28929:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1243.367059] LNetError: 28929:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1243.383425] LNet: Removed LNI 192.168.203.27@tcp [ 1243.902881] Key type .llcrypt unregistered [ 1243.909408] Key type ._llcrypt unregistered [ 1256.309577] Key type ._llcrypt registered [ 1256.311850] Key type .llcrypt registered [ 1257.391666] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 20:08:15 (1770685695) [ 1259.392084] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1259.466419] alg: No test for adler32 (adler32-zlib) [ 1260.582685] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 1260.825679] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 1262.503162] Key type lgssc registered [ 1263.464510] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1305.003578] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1308.776308] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1328.275463] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1330.948397] Lustre: Mounted lustre-client [ 1336.303773] Lustre: lustre-OST0000-osc-ffff9f0dd0dd5800: Connection to lustre-OST0000 (at 192.168.203.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1342.203428] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1352.499280] LustreError: 32050:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1352.537022] Lustre: Unmounted lustre-client [ 1377.920155] Key type lgssc unregistered [ 1378.182159] LNet: 32452:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1378.188350] LNetError: 32452:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1378.209869] LNet: Removed LNI 192.168.203.27@tcp [ 1378.900054] Key type .llcrypt unregistered [ 1378.902903] Key type ._llcrypt unregistered [ 1392.356390] Key type ._llcrypt registered [ 1392.358873] Key type .llcrypt registered [ 1393.693610] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 20:10:31 (1770685831) [ 1396.048329] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1396.072187] alg: No test for adler32 (adler32-zlib) [ 1397.155243] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 1397.360205] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 1399.047950] Key type lgssc registered [ 1399.926484] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1445.813995] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1449.556793] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1468.407929] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1471.204267] Lustre: Mounted lustre-client [ 1474.227396] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1477.443938] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1488.745350] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-02-0920h12m07s [ 1490.717978] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1497.074504] Lustre: lustre-MDT0000-mdc-ffff9f0de105d000: Connection to lustre-MDT0000 (at 192.168.203.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1500.804193] LustreError: 36155:0:(lmv_obd.c:1435:lmv_statfs()) lustre-MDT0000-mdc-ffff9f0de105d000: can't stat MDS #0: rc = -19 [ 1502.035688] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1508.334070] LustreError: MGC192.168.203.127@tcp: Connection to MGS (at 192.168.203.127@tcp) was lost; in progress operations using this service will fail [ 1508.361434] Lustre: Evicted from MGS (at 192.168.203.127@tcp) after server handle changed from 0xb473e11f985ffc25 to 0xb473e11f985ffebe [ 1508.370199] Lustre: MGC192.168.203.127@tcp: Connection restored to 192.168.203.127@tcp (at 192.168.203.127@tcp) [ 1509.422239] LustreError: lustre-MDT0000-mdc-ffff9f0de105d000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1509.454053] Lustre: lustre-MDT0000-mdc-ffff9f0de105d000: Connection restored to 192.168.203.127@tcp (at 192.168.203.127@tcp) [ 1513.692700] Lustre: DEBUG MARKER: Wait for df (36155) ... [ 1514.982907] Lustre: DEBUG MARKER: done [ 1516.711773] LustreError: 36766:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0de105d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1516.727604] LustreError: 36766:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1516.799455] Lustre: Unmounted lustre-client [ 1540.741564] Key type lgssc unregistered [ 1540.934219] LNet: 37168:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1540.940870] LNetError: 37168:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1541.991996] LNet: Removed LNI 192.168.203.27@tcp [ 1542.521886] Key type .llcrypt unregistered [ 1542.523327] Key type ._llcrypt unregistered [ 1568.212743] Key type ._llcrypt registered [ 1568.214353] Key type .llcrypt registered [ 1569.697176] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 20:13:27 (1770686007) [ 1572.084625] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1572.144207] alg: No test for adler32 (adler32-zlib) [ 1573.141678] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 1573.354762] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 1575.055193] Key type lgssc registered [ 1576.048431] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1613.714824] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1616.969729] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1633.404137] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1635.977516] Lustre: Mounted lustre-client [ 1639.615712] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-02-0920h14m38s [ 1640.961284] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1646.367483] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1648.101472] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1668.576359] Lustre: 41226:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1770686087/real 1770686087] req@00000000937fd3d6 x1856698855725312/t0(0) o101->lustre-MDT0000-mdc-ffff9f0de0150800@192.168.203.127@tcp:12/10 lens 576/1152 e 0 to 1 dl 1770686107 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1668.599584] Lustre: lustre-MDT0000-mdc-ffff9f0de0150800: Connection to lustre-MDT0000 (at 192.168.203.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1668.640155] Lustre: lustre-MDT0000-mdc-ffff9f0de0150800: Connection restored to 192.168.203.127@tcp (at 192.168.203.127@tcp) [ 1689.785412] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1692.931953] Lustre: DEBUG MARKER: done [ 1696.311459] LustreError: 41700:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0de0150800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1696.334102] LustreError: 41700:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1696.373510] Lustre: Unmounted lustre-client [ 1722.634775] Key type lgssc unregistered [ 1722.893271] LNet: 42101:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1722.897762] LNetError: 42101:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1722.911735] LNet: Removed LNI 192.168.203.27@tcp [ 1723.584035] Key type .llcrypt unregistered [ 1723.585460] Key type ._llcrypt unregistered [ 1750.852607] Key type ._llcrypt registered [ 1750.855553] Key type .llcrypt registered [ 1752.018294] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 20:16:30 (1770686190) [ 1753.424379] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1755.051355] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 20:16:33 (1770686193) [ 1758.023381] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1758.154606] alg: No test for adler32 (adler32-zlib) [ 1759.172595] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 1759.350261] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 1760.985623] Key type lgssc registered [ 1761.806128] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1784.171273] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 20:17:02 (1770686222) [ 1808.998530] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1811.868963] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1826.438838] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1829.200985] Lustre: Mounted lustre-client [ 1832.399334] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 1835.126105] LustreError: 46443:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0dd1ce3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1835.156754] LustreError: 46443:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1835.208710] Lustre: Unmounted lustre-client [ 1846.346221] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 1889.921401] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1893.221546] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1893.863966] Lustre: Mounted lustre-client [ 1896.774311] LustreError: 47702:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0de0157800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1896.799403] LustreError: 47702:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1896.806360] LustreError: 47702:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 1896.874096] Lustre: Unmounted lustre-client [ 1950.587286] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1953.697044] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1954.201172] Lustre: Mounted lustre-client [ 1957.261321] LustreError: 48818:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0dd0d2b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1957.284923] LustreError: 48818:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1957.287214] LustreError: 48818:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 1957.327795] Lustre: Unmounted lustre-client [ 1973.324917] Lustre: DEBUG MARKER: files compared the same [ 1994.220558] Key type lgssc unregistered [ 1994.420969] LNet: 49524:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1994.426925] LNetError: 49524:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1994.439288] LNet: Removed LNI 192.168.203.27@tcp [ 1994.927790] Key type .llcrypt unregistered [ 1994.929100] Key type ._llcrypt unregistered [ 2007.556500] Key type ._llcrypt registered [ 2007.558186] Key type .llcrypt registered [ 2008.687806] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 20:20:46 (1770686446) [ 2010.985295] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2011.049083] alg: No test for adler32 (adler32-zlib) [ 2012.140757] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 2012.403225] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 2014.087165] Key type lgssc registered [ 2015.429828] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2056.255539] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2059.919093] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2076.573512] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2082.342776] Lustre: Mounted lustre-client [ 2085.413804] LustreError: 52625:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0dd832e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2085.428318] LustreError: 52625:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2085.483290] Lustre: Unmounted lustre-client [ 2110.200855] Key type lgssc unregistered [ 2110.455810] LNet: 53028:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2110.461113] LNetError: 53028:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2110.497420] LNet: Removed LNI 192.168.203.27@tcp [ 2111.132474] Key type .llcrypt unregistered [ 2111.136610] Key type ._llcrypt unregistered [ 2125.888641] Key type ._llcrypt registered [ 2125.891188] Key type .llcrypt registered [ 2127.332025] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 20:22:45 (1770686565) [ 2129.417363] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2129.540171] alg: No test for adler32 (adler32-zlib) [ 2130.704108] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 2131.012236] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 2132.697148] Key type lgssc registered [ 2133.893353] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2208.988809] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2233.151633] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2235.771092] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2268.629630] Key type lgssc unregistered [ 2268.856705] LNet: 56422:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2268.862394] LNetError: 56422:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2268.884587] LNet: Removed LNI 192.168.203.27@tcp [ 2269.371541] Key type .llcrypt unregistered [ 2269.376243] Key type ._llcrypt unregistered [ 2281.438589] Key type ._llcrypt registered [ 2281.442781] Key type .llcrypt registered [ 2282.354066] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 20:25:20 (1770686720) [ 2285.117500] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2285.138790] alg: No test for adler32 (adler32-zlib) [ 2286.107181] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 2286.260608] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 2287.895196] Key type lgssc registered [ 2288.676339] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2344.131699] Lustre: Mounted lustre-client [ 2350.776391] LustreError: 58915:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0dd1004800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2350.796371] LustreError: 58915:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2350.836310] Lustre: Unmounted lustre-client [ 2389.974197] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 20:27:08 (1770686828) [ 2478.650427] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2496.524528] Lustre: Mounted lustre-client [ 2502.379840] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2502.383387] LustreError: 62885:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff9f0dd0d2a000: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 2502.393781] Lustre: 57405:0:(llite_lib.c:4233:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.203.127@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 2512.688533] LustreError: 62885:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2512.695364] LustreError: 62885:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 2512.717581] Lustre: Unmounted lustre-client [ 2556.096530] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 20:29:54 (1770686994) [ 2563.980753] Key type lgssc unregistered [ 2564.191360] LNet: 64094:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2564.195360] LNetError: 64094:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2564.207479] LNet: Removed LNI 192.168.203.27@tcp [ 2564.659691] Key type .llcrypt unregistered [ 2564.661789] Key type ._llcrypt unregistered [ 2572.323236] Key type ._llcrypt registered [ 2572.351989] Key type .llcrypt registered [ 2572.625291] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2572.634247] alg: No test for adler32 (adler32-zlib) [ 2573.601864] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 2573.781396] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 2575.439158] Key type lgssc registered [ 2576.129372] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2610.790112] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2614.181626] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2627.717435] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2630.287830] Lustre: Mounted lustre-client [ 2633.396190] LustreError: 66614:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0dc96a2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2633.418739] LustreError: 66614:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2633.488944] Lustre: Unmounted lustre-client [ 2655.919518] Key type lgssc unregistered [ 2656.108089] LNet: 67017:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2656.124511] LNetError: 67017:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2656.150682] LNet: Removed LNI 192.168.203.27@tcp [ 2656.647723] Key type .llcrypt unregistered [ 2656.649848] Key type ._llcrypt unregistered [ 2663.635958] Key type ._llcrypt registered [ 2663.638615] Key type .llcrypt registered [ 2663.968942] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2663.978134] alg: No test for adler32 (adler32-zlib) [ 2664.985727] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 2665.136601] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 2666.807226] Key type lgssc registered [ 2667.747467] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2733.680925] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2736.505956] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2748.927475] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2751.414447] Lustre: Mounted lustre-client [ 2757.798480] LustreError: 69953:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0dd3709000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2757.813342] LustreError: 69953:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2757.853680] Lustre: Unmounted lustre-client [ 2788.163349] Key type lgssc unregistered [ 2788.319428] LNet: 70356:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2788.328368] LNetError: 70356:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2789.350387] LNet: Removed LNI 192.168.203.27@tcp [ 2789.786371] Key type .llcrypt unregistered [ 2789.788271] Key type ._llcrypt unregistered [ 2800.749714] Key type ._llcrypt registered [ 2800.751977] Key type .llcrypt registered [ 2801.656646] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 20:34:00 (1770687240) [ 2803.399631] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2803.467471] alg: No test for adler32 (adler32-zlib) [ 2804.446202] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 2804.613787] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 2806.273453] Key type lgssc registered [ 2807.165436] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2842.135634] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2845.496996] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2861.382732] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2873.378631] Lustre: Mounted lustre-client [ 2877.181856] LustreError: 73477:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0de0d51000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2877.194650] LustreError: 73477:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2877.250241] Lustre: Unmounted lustre-client [ 2877.554913] Lustre: Mounted lustre-client [ 2878.820714] LustreError: 73556:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0dd370b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2878.827643] LustreError: 73556:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2878.829297] LustreError: 73556:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 2878.858406] Lustre: Unmounted lustre-client [ 2901.575193] Key type lgssc unregistered [ 2901.747464] LNet: 73958:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2901.751457] LNetError: 73958:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2901.765773] LNet: Removed LNI 192.168.203.27@tcp [ 2902.188545] Key type .llcrypt unregistered [ 2902.192823] Key type ._llcrypt unregistered [ 2908.872246] Key type ._llcrypt registered [ 2908.875927] Key type .llcrypt registered [ 2909.244834] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2909.263900] alg: No test for adler32 (adler32-zlib) [ 2910.289858] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 2910.469170] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 2912.111213] Key type lgssc registered [ 2912.957419] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2945.711650] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2948.307713] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2960.360590] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2961.792925] Lustre: Mounted lustre-client [ 2968.052380] LustreError: 76515:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0dd86e9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2968.070664] LustreError: 76515:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2968.102262] Lustre: Unmounted lustre-client [ 2989.824985] Key type lgssc unregistered [ 2989.989095] LNet: 76917:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2989.992947] LNetError: 76917:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2990.007283] LNet: Removed LNI 192.168.203.27@tcp [ 2990.449692] Key type .llcrypt unregistered [ 2990.451281] Key type ._llcrypt unregistered [ 2999.723729] Key type ._llcrypt registered [ 2999.724861] Key type .llcrypt registered [ 3000.507955] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 20:37:19 (1770687439) [ 3001.890532] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3001.906018] alg: No test for adler32 (adler32-zlib) [ 3002.921858] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 3003.103569] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 3004.777065] Key type lgssc registered [ 3005.622106] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3039.044968] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3041.369634] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3053.209371] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3055.760145] Lustre: Mounted lustre-client [ 3068.980541] Lustre: lustre: nosquash_nids is cleared [ 3069.005907] Lustre: lustre: root_squash is set to 500:500 [ 3076.293460] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.203.27@tcp 192.168.0.[2,10]@tcp [ 3090.449802] Lustre: lustre: nosquash_nids set to 192.168.203.27/24@tcp [ 3094.260804] LustreError: 80384:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0de110a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3094.287766] LustreError: 80384:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3094.357052] Lustre: Unmounted lustre-client [ 3117.175345] Key type lgssc unregistered [ 3117.382496] LNet: 80785:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3117.387041] LNetError: 80785:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3117.402223] LNet: Removed LNI 192.168.203.27@tcp [ 3117.949593] Key type .llcrypt unregistered [ 3117.951300] Key type ._llcrypt unregistered [ 3128.430485] Key type ._llcrypt registered [ 3128.434048] Key type .llcrypt registered [ 3129.332329] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 20:39:27 (1770687567) [ 3131.403234] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3132.842679] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 20:39:31 (1770687571) [ 3134.745934] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3134.770405] alg: No test for adler32 (adler32-zlib) [ 3135.705995] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 3135.834237] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 3137.463185] Key type lgssc registered [ 3138.255772] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3170.398761] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3173.020925] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3185.322989] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3186.699205] Lustre: lustre: root_squash is set to 500:500 [ 3186.717176] Lustre: lustre: nosquash_nids set to 192.168.203.27/24@tcp [ 3187.762526] Lustre: Mounted lustre-client [ 3194.154682] LustreError: 84251:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0dc2c2c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3194.170738] LustreError: 84251:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3194.212184] Lustre: Unmounted lustre-client [ 3216.272738] Key type lgssc unregistered [ 3216.465654] LNet: 84652:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3216.471327] LNetError: 84652:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3216.482535] LNet: Removed LNI 192.168.203.27@tcp [ 3216.995499] Key type .llcrypt unregistered [ 3216.998096] Key type ._llcrypt unregistered [ 3226.189804] Key type ._llcrypt registered [ 3226.192975] Key type .llcrypt registered [ 3227.109372] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 3228.295536] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 20:41:06 (1770687666) [ 3237.280189] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3237.316310] alg: No test for adler32 (adler32-zlib) [ 3238.227189] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 3238.338428] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 3239.943515] Key type lgssc registered [ 3240.608467] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3283.668359] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3285.419812] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3295.630216] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3341.726956] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3343.851233] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3353.476748] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3364.956616] Lustre: Mounted lustre-client [ 3380.176660] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9f0de0155000.ost_server_uuid 50 [ 3380.953196] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9f0de0155000.ost_server_uuid in FULL state after 0 sec [ 3381.366191] Lustre: Mounted lustre-client [ 3382.685940] LustreError: 91536:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0dd370f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3382.694201] LustreError: 91536:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 3382.726948] Lustre: Unmounted lustre-client [ 3412.291879] Key type lgssc unregistered [ 3412.433987] LNet: 92105:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3412.437961] LNetError: 92105:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3412.448165] LNet: Removed LNI 192.168.203.27@tcp [ 3412.718816] Key type .llcrypt unregistered [ 3412.719897] Key type ._llcrypt unregistered [ 3424.539135] Key type ._llcrypt registered [ 3424.540407] Key type .llcrypt registered [ 3425.120420] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 20:44:23 (1770687863) [ 3432.454544] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3432.480173] alg: No test for adler32 (adler32-zlib) [ 3433.371957] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 3433.471610] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 3435.071349] Key type lgssc registered [ 3435.626744] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3471.852631] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3473.533984] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3483.047390] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3484.269375] Lustre: Mounted lustre-client [ 3500.004749] Lustre: lustre-OST0000-osc-ffff9f0dd2fc1000: Connection to lustre-OST0000 (at 192.168.203.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3505.122305] Lustre: lustre-MDT0000-mdc-ffff9f0dd2fc1000: Connection to lustre-MDT0000 (at 192.168.203.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3506.143686] Lustre: 93463:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1770687929/real 1770687929] req@00000000caff520d x1856700806075520/t0(0) o400->lustre-OST0000-osc-ffff9f0dd2fc1000@192.168.203.127@tcp:28/4 lens 224/224 e 0 to 1 dl 1770687945 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3511.263230] Lustre: 93463:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1770687934/real 1770687934] req@0000000001386231 x1856700806076032/t0(0) o400->lustre-OST0000-osc-ffff9f0dd2fc1000@192.168.203.127@tcp:28/4 lens 224/224 e 0 to 1 dl 1770687950 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3520.486495] LustreError: MGC192.168.203.127@tcp: Connection to MGS (at 192.168.203.127@tcp) was lost; in progress operations using this service will fail [ 3520.497672] Lustre: 96537:0:(mgc_request.c:1910:mgc_process_log()) MGC192.168.203.127@tcp: IR log lustre-cliir failed, not fatal: rc = -5 [ 3520.502613] Lustre: Evicted from MGS (at 192.168.203.127@tcp) after server handle changed from 0x1fc132f4d21c4c17 to 0x1fc132f4d21c4eb0 [ 3520.513554] Lustre: MGC192.168.203.127@tcp: Connection restored to 192.168.203.127@tcp (at 192.168.203.127@tcp) [ 3521.069574] Lustre: lustre-MDT0000-mdc-ffff9f0dd2fc1000: Connection restored to 192.168.203.127@tcp (at 192.168.203.127@tcp) [ 3521.606162] LustreError: 97260:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0dd2fc1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3521.617381] LustreError: 97260:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3521.648813] Lustre: Unmounted lustre-client [ 3541.115756] Key type lgssc unregistered [ 3541.245388] LNet: 97661:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3541.248116] LNetError: 97661:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3541.257601] LNet: Removed LNI 192.168.203.27@tcp [ 3541.554378] Key type .llcrypt unregistered [ 3541.555768] Key type ._llcrypt unregistered [ 3548.608063] Key type ._llcrypt registered [ 3548.609314] Key type .llcrypt registered [ 3549.231366] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 20:46:28 (1770687988) [ 3561.461741] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3561.468293] alg: No test for adler32 (adler32-zlib) [ 3562.356953] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 3562.459852] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 3564.047176] Key type lgssc registered [ 3564.579484] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3581.908290] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3621.883436] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3623.363292] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3633.039596] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3640.811501] Lustre: Mounted lustre-client [ 3641.656366] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 3642.517730] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 20:48:01 (1770688081) [ 3743.726191] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3754.026213] LustreError: 108406:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3754.038963] Lustre: Unmounted lustre-client [ 3812.177796] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3813.820648] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3823.178954] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3842.514826] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 20:51:21 (1770688281) [ 3880.135422] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3881.800766] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3890.968337] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3891.323415] Lustre: Mounted lustre-client [ 3892.837272] LustreError: 115795:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0dd1a0d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3892.856119] LustreError: 115795:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3892.859571] LustreError: 115795:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 3892.900995] Lustre: Unmounted lustre-client [ 3912.792479] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 20:52:31 (1770688351) [ 3948.965747] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3950.426769] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3960.106353] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3960.320904] Lustre: Mounted lustre-client [ 3961.716220] LustreError: 119831:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0de110c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3961.725404] LustreError: 119831:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3961.728389] LustreError: 119831:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 3961.762071] Lustre: Unmounted lustre-client [ 3980.266488] Key type lgssc unregistered [ 3980.395747] LNet: 120232:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3980.399467] LNetError: 120232:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3980.406990] LNet: Removed LNI 192.168.203.27@tcp [ 3980.656813] Key type .llcrypt unregistered [ 3980.658165] Key type ._llcrypt unregistered [ 3986.562282] Key type ._llcrypt registered [ 3986.563703] Key type .llcrypt registered [ 3987.133791] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 20:53:46 (1770688426) [ 3988.044773] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3988.057950] alg: No test for adler32 (adler32-zlib) [ 3988.946238] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 3989.037836] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 3990.623131] Key type lgssc registered [ 3991.062154] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4010.401092] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4011.886646] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4019.452928] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4030.965231] Lustre: Mounted lustre-client [ 4041.808664] LustreError: 123328:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0dd838b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4041.815314] LustreError: 123328:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4041.835092] Lustre: Unmounted lustre-client [ 4060.326918] Key type lgssc unregistered [ 4060.474533] LNet: 123729:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4060.478187] LNetError: 123729:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4060.492569] LNet: Removed LNI 192.168.203.27@tcp [ 4060.804198] Key type .llcrypt unregistered [ 4060.806346] Key type ._llcrypt unregistered [ 4066.558614] Key type ._llcrypt registered [ 4066.559703] Key type .llcrypt registered [ 4067.081640] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 20:55:06 (1770688506) [ 4067.923565] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4067.967815] alg: No test for adler32 (adler32-zlib) [ 4068.853469] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 4068.956076] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 4070.551209] Key type lgssc registered [ 4071.055575] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4091.122464] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4092.578848] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4099.550422] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4110.328783] Lustre: Mounted lustre-client [ 4115.428910] Lustre: lustre-OST0000-osc-ffff9f0dd2fc2800: Connection to lustre-OST0000 (at 192.168.203.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4129.147055] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff9f0dd2fc2800.ost_server_uuid 50 [ 4129.692243] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9f0dd2fc2800.ost_server_uuid in DISCONN state after 0 sec [ 4130.257834] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 4139.607649] LustreError: 127396:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0dd2fc2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4139.612756] LustreError: 127396:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4139.633161] Lustre: Unmounted lustre-client [ 4150.970309] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 20:56:29 (1770688589) [ 4162.064837] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4163.433540] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4170.208907] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4178.328993] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4178.527372] Lustre: Mounted lustre-client [ 4183.522919] Lustre: lustre-OST0000-osc-ffff9f0dd1a0f800: Connection to lustre-OST0000 (at 192.168.203.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4198.291464] LustreError: 130041:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0dd1a0f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4198.303196] LustreError: 130041:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 4198.305779] LustreError: 130041:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4198.333507] Lustre: Unmounted lustre-client [ 4221.830748] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 20:57:40 (1770688660) [ 4232.357363] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4233.660202] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4240.280429] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4246.899285] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4247.105303] Lustre: Mounted lustre-client [ 4252.133941] Lustre: lustre-OST0000-osc-ffff9f0de110f000: Connection to lustre-OST0000 (at 192.168.203.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4272.471911] LustreError: 132789:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0de110f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4272.476407] LustreError: 132789:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 4272.482100] LustreError: 132789:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 4272.484610] LustreError: 132789:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 4272.508149] Lustre: Unmounted lustre-client [ 4296.478111] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 20:58:55 (1770688735) [ 4327.754133] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4329.025937] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4337.047160] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4369.906887] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4371.263205] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4377.991283] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4400.767683] Lustre: Mounted lustre-client [ 4428.404439] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4428.957798] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 4429.286477] LustreError: 139375:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0dd838e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4429.290151] LustreError: 139375:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 4429.295387] LustreError: 139375:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4429.297104] LustreError: 139375:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 4429.317448] Lustre: Unmounted lustre-client [ 4439.738412] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 21:01:18 (1770688878) [ 4450.164161] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4451.382263] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4457.554281] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4477.365838] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4495.392741] Lustre: Mounted lustre-client [ 4523.716508] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4524.373270] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 4526.052054] Lustre: lustre-OST0001-osc-ffff9f0dd13e3000: Connection to lustre-OST0001 (at 192.168.203.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4528.111617] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4538.406913] LustreError: 142946:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 4538.410195] LustreError: 142946:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4538.427174] Lustre: Unmounted lustre-client [ 4568.571993] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 21:03:27 (1770689007) [ 4578.574039] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4579.783285] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4586.050298] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4591.602046] Lustre: Mounted lustre-client [ 4598.632446] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4601.228730] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 2 sec [ 4605.392458] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9f0dc8c3e800.ost_server_uuid 50 [ 4605.886169] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9f0dc8c3e800.ost_server_uuid in FULL state after 0 sec [ 4606.481075] LustreError: 146374:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0dc8c3e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4606.486561] LustreError: 146374:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 4606.488848] LustreError: 146374:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 4606.512116] Lustre: Unmounted lustre-client [ 4606.612450] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 4606.614198] Lustre: Skipped 3 previous similar messages [ 4607.027649] LustreError: 146437:0:(sec.c:410:import_sec_validate_get()) import 000000006c8f8ab9 (NEW) with no sec [ 4646.181797] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 21:04:45 (1770689085) [ 4656.354333] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4657.553123] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4663.551795] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4669.766587] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4669.913500] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 4675.064784] Lustre: Mounted lustre-client [ 4675.065844] Lustre: Skipped 1 previous similar message [ 4685.901326] LustreError: 149295:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0dd838e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4685.905377] LustreError: 149295:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 4685.915780] LustreError: 149295:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 4685.918332] LustreError: 149295:0:(obd_class.h:479:obd_check_dev()) Skipped 18 previous similar messages [ 4685.949110] Lustre: Unmounted lustre-client [ 4685.950499] Lustre: Skipped 1 previous similar message [ 4711.286303] Key type lgssc unregistered [ 4711.410436] LNet: 149741:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4711.412683] LNetError: 149741:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4711.422884] LNet: Removed LNI 192.168.203.27@tcp [ 4711.673537] Key type .llcrypt unregistered [ 4711.674938] Key type ._llcrypt unregistered [ 4716.596574] Key type ._llcrypt registered [ 4716.597474] Key type .llcrypt registered [ 4717.104640] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 21:05:56 (1770689156) [ 4717.422502] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4717.449874] alg: No test for adler32 (adler32-zlib) [ 4718.315213] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 4718.399281] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 4719.983130] Key type lgssc registered [ 4720.384743] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4738.508251] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4739.843167] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4746.380651] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4753.417943] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4754.690123] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 4760.048407] Lustre: Mounted lustre-client [ 4767.728698] LustreError: lustre-MDT0001-mdc-ffff9f0dca1c7000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 4767.737828] Lustre: lustre-MDT0001-mdc-ffff9f0dca1c7000: Connection restored to 192.168.203.127@tcp (at 192.168.203.127@tcp) [ 4769.846812] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4770.416891] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 4782.572756] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 4783.626805] LustreError: 153975:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0dca1c7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4783.630842] LustreError: 153975:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 4783.651389] Lustre: Unmounted lustre-client [ 4804.353487] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 21:07:23 (1770689243) [ 4835.006518] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4836.150338] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4843.316774] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4853.742603] Lustre: Mounted lustre-client [ 4863.316382] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4869.923967] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 6 sec [ 4878.818905] Lustre: lustre-OST0001-osc-ffff9f0dd1a0c800: Connection to lustre-OST0001 (at 192.168.203.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4882.204052] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4892.455978] LustreError: 158739:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 4892.458697] LustreError: 158739:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 4892.474375] Lustre: Unmounted lustre-client [ 4924.095578] Key type lgssc unregistered [ 4924.210537] LNet: 159145:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4924.214187] LNetError: 159145:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4924.225677] LNet: Removed LNI 192.168.203.27@tcp [ 4924.444284] Key type .llcrypt unregistered [ 4924.445789] Key type ._llcrypt unregistered [ 4934.379616] Key type ._llcrypt registered [ 4934.380327] Key type .llcrypt registered [ 4934.837619] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 21:09:33 (1770689373) [ 4935.538307] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4935.579099] alg: No test for adler32 (adler32-zlib) [ 4936.429343] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 4936.511096] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 4938.103144] Key type lgssc registered [ 4938.484571] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4955.162634] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4956.301446] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4962.036367] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4968.405468] Lustre: Mounted lustre-client [ 5005.139979] LustreError: 162402:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0dd1d20000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5005.148577] LustreError: 162402:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5005.172139] Lustre: Unmounted lustre-client [ 5021.122812] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5021.267258] Lustre: Mounted lustre-client [ 5053.819777] LustreError: 163057:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0dd2850800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5053.830117] LustreError: 163057:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5053.832816] LustreError: 163057:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5053.855861] Lustre: Unmounted lustre-client [ 5070.973043] Key type lgssc unregistered [ 5071.091521] LNet: 163464:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5071.094690] LNetError: 163464:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5071.103211] LNet: Removed LNI 192.168.203.27@tcp [ 5071.347071] Key type .llcrypt unregistered [ 5071.348053] Key type ._llcrypt unregistered [ 5076.057603] Key type ._llcrypt registered [ 5076.058487] Key type .llcrypt registered [ 5076.470026] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 21:11:55 (1770689515) [ 5077.205976] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5077.211139] alg: No test for adler32 (adler32-zlib) [ 5078.061083] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 5078.143392] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 5079.727185] Key type lgssc registered [ 5080.092563] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5097.018574] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5098.244586] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5104.164269] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5114.863275] Lustre: Mounted lustre-client [ 5121.293479] LustreError: 166712:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0de0d50800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5121.300194] LustreError: 166712:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5121.319470] Lustre: Unmounted lustre-client [ 5138.279201] Key type lgssc unregistered [ 5138.405450] LNet: 167115:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5138.407367] LNetError: 167115:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5138.413728] LNet: Removed LNI 192.168.203.27@tcp [ 5138.648692] Key type .llcrypt unregistered [ 5138.649993] Key type ._llcrypt unregistered [ 5142.738663] Key type ._llcrypt registered [ 5142.740191] Key type .llcrypt registered [ 5142.876412] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5142.880737] alg: No test for adler32 (adler32-zlib) [ 5143.725288] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 5143.805102] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 5145.383146] Key type lgssc registered [ 5145.757468] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5161.839118] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5162.988325] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5168.526303] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5174.747728] Lustre: Mounted lustre-client [ 5177.305949] LustreError: 169676:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0dd35be000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5177.313685] LustreError: 169676:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5177.336205] Lustre: Unmounted lustre-client [ 5194.376432] Key type lgssc unregistered [ 5194.494667] LNet: 170079:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5194.497416] LNetError: 170079:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5194.505665] LNet: Removed LNI 192.168.203.27@tcp [ 5194.754346] Key type .llcrypt unregistered [ 5194.755883] Key type ._llcrypt unregistered [ 5200.418712] Key type ._llcrypt registered [ 5200.419647] Key type .llcrypt registered [ 5200.880325] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 21:13:59 (1770689639) [ 5201.758371] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5201.824105] alg: No test for adler32 (adler32-zlib) [ 5202.682570] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 5202.767952] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 5204.359157] Key type lgssc registered [ 5204.723200] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5222.067626] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5223.316903] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5229.275028] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5244.912879] Lustre: Mounted lustre-client [ 5251.607440] LustreError: 173328:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0dc2c2c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5251.615874] LustreError: 173328:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5251.638175] Lustre: Unmounted lustre-client [ 5268.901662] Key type lgssc unregistered [ 5269.035610] LNet: 173730:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5269.038433] LNetError: 173730:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5269.047655] LNet: Removed LNI 192.168.203.27@tcp [ 5269.293892] Key type .llcrypt unregistered [ 5269.295172] Key type ._llcrypt unregistered [ 5273.337574] Key type ._llcrypt registered [ 5273.339240] Key type .llcrypt registered [ 5273.567146] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5273.572210] alg: No test for adler32 (adler32-zlib) [ 5274.438772] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 5274.520545] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 5276.098354] Key type lgssc registered [ 5276.465920] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5292.975717] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5294.047472] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5299.759634] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5315.567500] Lustre: Mounted lustre-client [ 5318.286589] LustreError: 176295:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0de0d55000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5318.293901] LustreError: 176295:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5318.312154] Lustre: Unmounted lustre-client [ 5335.541989] Key type lgssc unregistered [ 5335.647523] LNet: 176696:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5335.651828] LNetError: 176696:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5335.661758] LNet: Removed LNI 192.168.203.27@tcp [ 5335.893740] Key type .llcrypt unregistered [ 5335.895118] Key type ._llcrypt unregistered [ 5340.878803] Key type ._llcrypt registered [ 5340.880113] Key type .llcrypt registered [ 5341.333312] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 21:16:20 (1770689780) [ 5348.440915] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5348.453338] alg: No test for adler32 (adler32-zlib) [ 5349.304822] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 5349.384713] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 5350.967178] Key type lgssc registered [ 5351.341145] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5378.311244] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5379.422309] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5386.695234] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5407.753841] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 21:17:26 (1770689846) [ 5416.662576] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5417.807130] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5423.401231] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5424.556138] Lustre: Mounted lustre-client [ 5426.427561] LustreError: 183189:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0de110a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5426.437827] LustreError: 183189:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5426.459112] Lustre: Unmounted lustre-client [ 5443.588277] Key type lgssc unregistered [ 5443.689502] LNet: 183591:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5443.691415] LNetError: 183591:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5443.703607] LNet: Removed LNI 192.168.203.27@tcp [ 5443.920957] Key type .llcrypt unregistered [ 5443.921992] Key type ._llcrypt unregistered [ 5448.738425] Key type ._llcrypt registered [ 5448.739883] Key type .llcrypt registered [ 5449.159354] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 5449.670104] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 21:18:08 (1770689888) [ 5455.318074] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5455.358217] alg: No test for adler32 (adler32-zlib) [ 5456.217950] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 5456.301959] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 5457.887119] Key type lgssc registered [ 5458.228793] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5489.936830] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5491.143789] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5498.563768] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid [ 5504.313963] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid [ 5505.472051] Lustre: Mounted lustre-client [ 5511.224406] LustreError: 189111:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0dc9518800: lov tgt 1000 not cleaned! deathrow=0, lovrc=1 [ 5511.230870] LustreError: 189111:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5511.251680] Lustre: Unmounted lustre-client [ 5571.696112] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5572.927680] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5580.202437] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5610.524863] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 21:20:49 (1770690049) [ 5610.995892] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 5611.528846] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 21:20:50 (1770690050) [ 5628.704048] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5629.713315] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5654.043574] Key type lgssc unregistered [ 5654.163659] LNet: 195271:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5654.165972] LNetError: 195271:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5654.173546] LNet: Removed LNI 192.168.203.27@tcp [ 5654.427876] Key type .llcrypt unregistered [ 5654.428986] Key type ._llcrypt unregistered [ 5659.364796] Key type ._llcrypt registered [ 5659.366216] Key type .llcrypt registered [ 5659.848564] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 21:21:38 (1770690098) [ 5693.134857] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5693.142357] alg: No test for adler32 (adler32-zlib) [ 5694.029308] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 5694.123819] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 5695.719295] Key type lgssc registered [ 5696.143802] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5710.158917] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5711.379878] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5728.397372] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5739.539535] Key type lgssc unregistered [ 5739.657493] LNet: 198896:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5739.660167] LNetError: 198896:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5739.668407] LNet: Removed LNI 192.168.203.27@tcp [ 5739.943467] Key type .llcrypt unregistered [ 5739.945191] Key type ._llcrypt unregistered [ 5745.176464] Key type ._llcrypt registered [ 5745.177831] Key type .llcrypt registered [ 5745.653118] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 21:23:04 (1770690184) [ 5746.538304] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5746.545847] alg: No test for adler32 (adler32-zlib) [ 5747.397388] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 5747.488990] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 5749.071132] Key type lgssc registered [ 5749.505139] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5766.403313] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5767.588454] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5773.807933] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5784.562566] Lustre: Mounted lustre-client [ 5789.667299] Lustre: lustre-MDT0000-mdc-ffff9f0dd13e4800: Connection to lustre-MDT0000 (at 192.168.203.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5794.786781] Lustre: lustre-MDT0001-mdc-ffff9f0dd13e4800: Connection to lustre-MDT0001 (at 192.168.203.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5810.145280] LustreError: MGC192.168.203.127@tcp: Connection to MGS (at 192.168.203.127@tcp) was lost; in progress operations using this service will fail [ 5810.151511] Lustre: Evicted from MGS (at 192.168.203.127@tcp) after server handle changed from 0x4f4bde4354e337b3 to 0x4f4bde4354e35113 [ 5810.156860] Lustre: MGC192.168.203.127@tcp: Connection restored to 192.168.203.127@tcp (at 192.168.203.127@tcp) [ 5826.841608] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5829.603073] LustreError: lustre-MDT0000-mdc-ffff9f0dd13e4800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 5829.631398] Lustre: lustre-MDT0000-mdc-ffff9f0dd13e4800: Connection restored to 192.168.203.127@tcp (at 192.168.203.127@tcp) [ 5830.447873] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 3 sec [ 5831.596551] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5832.180454] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 5832.777400] LustreError: 203315:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0dd13e4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5832.783334] LustreError: 203315:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5832.803202] Lustre: Unmounted lustre-client [ 5850.001932] Key type lgssc unregistered [ 5850.121610] LNet: 203716:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5850.124105] LNetError: 203716:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5850.130706] LNet: Removed LNI 192.168.203.27@tcp [ 5850.389273] Key type .llcrypt unregistered [ 5850.390411] Key type ._llcrypt unregistered [ 5855.524100] Key type ._llcrypt registered [ 5855.525013] Key type .llcrypt registered [ 5856.032634] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 21:24:54 (1770690294) [ 5856.955052] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5856.963754] alg: No test for adler32 (adler32-zlib) [ 5857.818621] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 5857.901449] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 5859.487165] Key type lgssc registered [ 5859.874548] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5876.851313] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5877.989192] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5899.021698] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5900.252875] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5909.424205] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5915.685210] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5936.569885] Key type lgssc unregistered [ 5936.686323] LNet: 208808:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5936.688415] LNetError: 208808:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5936.697275] LNet: Removed LNI 192.168.203.27@tcp [ 5936.953417] Key type .llcrypt unregistered [ 5936.955144] Key type ._llcrypt unregistered [ 5958.263280] Key type ._llcrypt registered [ 5958.264197] Key type .llcrypt registered [ 5958.694796] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 21:26:37 (1770690397) [ 5970.911977] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5970.939539] alg: No test for adler32 (adler32-zlib) [ 5971.789369] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 5971.871726] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 5973.455166] Key type lgssc registered [ 5973.832105] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6008.782848] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6010.603258] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6024.387505] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6048.372400] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 21:28:07 (1770690487) [ 6051.384979] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 21:28:10 (1770690490) [ 6065.062548] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6066.679636] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6074.884833] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6080.514503] Lustre: Mounted lustre-client [ 6083.949142] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [ 6084.628890] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 6085.266704] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 6085.944860] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [ 6090.722565] Lustre: lustre-MDT0000-mdc-ffff9f0dd13e4000: Connection to lustre-MDT0000 (at 192.168.203.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6100.961734] LustreError: MGC192.168.203.127@tcp: Connection to MGS (at 192.168.203.127@tcp) was lost; in progress operations using this service will fail [ 6100.967782] Lustre: Evicted from MGS (at 192.168.203.127@tcp) after server handle changed from 0x5b44c403308a7f03 to 0x5b44c403308a816b [ 6100.971618] Lustre: MGC192.168.203.127@tcp: Connection restored to 192.168.203.127@tcp (at 192.168.203.127@tcp) [ 6105.641773] Lustre: lustre-MDT0000-mdc-ffff9f0dd13e4000: Connection restored to 192.168.203.127@tcp (at 192.168.203.127@tcp) [ 6106.835850] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6107.416777] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6109.339723] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [ 6111.205968] Lustre: lustre-MDT0000-mdc-ffff9f0dd13e4000: Connection to lustre-MDT0000 (at 192.168.203.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6126.559133] Lustre: 210697:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1770690550/real 1770690550] req@00000000846edd16 x1856703468420096/t0(0) o400->MGC192.168.203.127@tcp@192.168.203.127@tcp:26/25 lens 224/224 e 0 to 1 dl 1770690566 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 6126.571626] LustreError: MGC192.168.203.127@tcp: Connection to MGS (at 192.168.203.127@tcp) was lost; in progress operations using this service will fail [ 6126.592853] Lustre: Evicted from MGS (at 192.168.203.127@tcp) after server handle changed from 0x5b44c403308a816b to 0x5b44c403308a846d [ 6126.599658] Lustre: MGC192.168.203.127@tcp: Connection restored to 192.168.203.127@tcp (at 192.168.203.127@tcp) [ 6128.685404] Lustre: lustre-MDT0000-mdc-ffff9f0dd13e4000: Connection restored to 192.168.203.127@tcp (at 192.168.203.127@tcp) [ 6130.072673] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6130.684145] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6132.259573] LustreError: 218928:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0dd13e4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6132.266812] LustreError: 218928:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6132.289589] Lustre: Unmounted lustre-client [ 6167.710877] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6169.072306] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6176.166326] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6187.000848] Lustre: Mounted lustre-client [ 6187.397087] LustreError: 220539:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0dc93df000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6187.406835] LustreError: 220539:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6187.410171] LustreError: 220539:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 6187.434944] Lustre: Unmounted lustre-client [ 6197.247079] Key type lgssc unregistered [ 6197.379535] LNet: 220941:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6197.384533] LNetError: 220941:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6197.398262] LNet: Removed LNI 192.168.203.27@tcp [ 6197.667698] Key type .llcrypt unregistered [ 6197.669343] Key type ._llcrypt unregistered [ 6203.479663] Key type ._llcrypt registered [ 6203.480731] Key type .llcrypt registered [ 6203.989377] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 21:30:42 (1770690642) [ 6205.500933] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6205.568888] alg: No test for adler32 (adler32-zlib) [ 6206.446106] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 6206.535086] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 6208.119156] Key type lgssc registered [ 6208.549323] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6227.504274] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6228.841248] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6235.630488] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6247.409305] Lustre: Mounted lustre-client [ 6250.425498] LustreError: 224103:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0dd2fc6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6250.432747] LustreError: 224103:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6250.453131] Lustre: Unmounted lustre-client [ 6283.047239] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6284.298119] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6291.024691] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6301.679584] Lustre: Mounted lustre-client [ 6307.908306] LustreError: 225699:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0de110b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6307.919873] LustreError: 225699:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6307.923237] LustreError: 225699:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 6307.949168] Lustre: Unmounted lustre-client [ 6342.217457] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6343.695153] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6350.734404] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6361.082785] Lustre: Mounted lustre-client [ 6361.469472] LustreError: 227311:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0de0153800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6361.477765] LustreError: 227311:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6361.480489] LustreError: 227311:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 6361.504135] Lustre: Unmounted lustre-client [ 6379.277348] Key type lgssc unregistered [ 6379.401437] LNet: 227713:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6379.404286] LNetError: 227713:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6379.412614] LNet: Removed LNI 192.168.203.27@tcp [ 6379.640593] Key type .llcrypt unregistered [ 6379.641705] Key type ._llcrypt unregistered [ 6384.879150] Key type ._llcrypt registered [ 6384.880385] Key type .llcrypt registered [ 6385.347106] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 21:33:44 (1770690824) [ 6386.446785] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6386.509642] alg: No test for adler32 (adler32-zlib) [ 6387.380959] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 6387.468441] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 6389.055174] Key type lgssc registered [ 6389.478644] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6405.422630] Key type lgssc unregistered [ 6405.545714] LNet: 230025:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6405.549343] LNetError: 230025:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6405.561403] LNet: Removed LNI 192.168.203.27@tcp [ 6405.831513] Key type .llcrypt unregistered [ 6405.832743] Key type ._llcrypt unregistered [ 6415.862663] Key type ._llcrypt registered [ 6415.863958] Key type .llcrypt registered [ 6416.087856] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6416.093388] alg: No test for adler32 (adler32-zlib) [ 6416.995672] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 6417.098035] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 6418.687184] Key type lgssc registered [ 6419.176697] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6448.541409] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6449.798879] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6457.489338] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6478.764183] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [ 6479.345445] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 21:35:18 (1770690918) [ 6489.168756] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6490.367261] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6496.372036] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6503.853254] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6505.051239] Lustre: Mounted lustre-client [ 6510.050490] Lustre: lustre-OST0001-osc-ffff9f0de0d55000: Connection to lustre-OST0001 (at 192.168.203.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6512.744109] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6522.983618] LustreError: 236504:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 6523.000159] Lustre: Unmounted lustre-client [ 6546.534717] Key type lgssc unregistered [ 6546.654720] LNet: 236909:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6546.658336] LNetError: 236909:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6546.670050] LNet: Removed LNI 192.168.203.27@tcp [ 6546.920796] Key type .llcrypt unregistered [ 6546.922982] Key type ._llcrypt unregistered [ 6557.240336] Key type ._llcrypt registered [ 6557.241423] Key type .llcrypt registered [ 6557.715037] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 21:36:36 (1770690996) [ 6562.398643] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6562.460732] alg: No test for adler32 (adler32-zlib) [ 6563.335605] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 6563.432190] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 6565.023199] Key type lgssc registered [ 6565.447147] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6583.327572] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6584.614663] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6596.049306] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 21:37:15 (1770691035) [ 6606.257355] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6607.644690] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6614.238509] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6615.395768] Lustre: Mounted lustre-client [ 6623.208533] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 6626.897351] LustreError: 241933:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6626.923191] Lustre: Unmounted lustre-client [ 6676.920626] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6678.160495] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6712.181769] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6722.550147] Lustre: Mounted lustre-client [ 6723.045478] LustreError: 244005:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0de110c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6723.054842] LustreError: 244005:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6723.057230] LustreError: 244005:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 6723.082159] Lustre: Unmounted lustre-client [ 6740.910182] Key type lgssc unregistered [ 6741.032351] LNet: 244408:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6741.035109] LNetError: 244408:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6741.043442] LNet: Removed LNI 192.168.203.27@tcp [ 6741.281465] Key type .llcrypt unregistered [ 6741.282932] Key type ._llcrypt unregistered [ 6751.814814] Key type ._llcrypt registered [ 6751.816407] Key type .llcrypt registered [ 6752.008442] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6752.015458] alg: No test for adler32 (adler32-zlib) [ 6752.898570] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 6752.993393] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 6754.591188] Key type lgssc registered [ 6755.041611] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6785.257430] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6786.432590] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6793.982236] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6824.021138] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [ 6824.597945] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 21:41:03 (1770691263) [ 6834.334715] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6835.524052] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6841.730214] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6853.102790] Lustre: Mounted lustre-client [ 6853.948931] LustreError: 250444:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0dc8c38800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6853.956201] LustreError: 250444:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6853.981184] Lustre: Unmounted lustre-client [ 6871.297941] Key type lgssc unregistered [ 6871.417651] LNet: 250846:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6871.420633] LNetError: 250846:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6871.429682] LNet: Removed LNI 192.168.203.27@tcp [ 6871.666338] Key type .llcrypt unregistered [ 6871.667206] Key type ._llcrypt unregistered [ 6877.029833] Key type ._llcrypt registered [ 6877.031136] Key type .llcrypt registered [ 6877.466562] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [ 6878.014629] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 21:41:57 (1770691317) [ 6881.148511] Key type .llcrypt unregistered [ 6881.150429] Key type ._llcrypt unregistered [ 6883.863435] Key type ._llcrypt registered [ 6883.864639] Key type .llcrypt registered [ 6884.046435] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6884.054310] alg: No test for adler32 (adler32-zlib) [ 6884.921944] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 6885.004686] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 6886.591129] Key type lgssc registered [ 6886.985144] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6906.108115] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6916.591463] Lustre: Mounted lustre-client [ 6917.079556] LustreError: 253854:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0dd0dd0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6917.087647] LustreError: 253854:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6917.111155] Lustre: Unmounted lustre-client [ 6934.042243] Key type lgssc unregistered [ 6934.164398] LNet: 254255:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6934.166600] LNetError: 254255:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6934.174647] LNet: Removed LNI 192.168.203.27@tcp [ 6934.398127] Key type .llcrypt unregistered [ 6934.399064] Key type ._llcrypt unregistered [ 6939.223958] Key type ._llcrypt registered [ 6939.225071] Key type .llcrypt registered [ 6939.717239] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 21:42:58 (1770691378) [ 6940.543430] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6940.578740] alg: No test for adler32 (adler32-zlib) [ 6941.436962] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 6941.518691] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 6943.103133] Key type lgssc registered [ 6943.496304] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7002.539977] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7011.727634] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7012.849712] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7018.479178] Lustre: Mounted lustre-client [ 7018.929942] LustreError: 257293:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0dd0d2c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7018.936217] LustreError: 257293:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7018.957726] Lustre: Unmounted lustre-client [ 7036.246319] Key type lgssc unregistered [ 7036.376643] LNet: 257696:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7036.380141] LNetError: 257696:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7036.387836] LNet: Removed LNI 192.168.203.27@tcp [ 7036.631754] Key type .llcrypt unregistered [ 7036.632693] Key type ._llcrypt unregistered [ 7041.612747] Key type ._llcrypt registered [ 7041.614177] Key type .llcrypt registered [ 7042.075752] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 21:44:41 (1770691481) [ 7042.908733] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7042.917197] alg: No test for adler32 (adler32-zlib) [ 7043.779488] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 7043.863237] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 7045.447190] Key type lgssc registered [ 7045.862639] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7063.120227] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7064.225497] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7070.083847] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7085.549225] Lustre: Mounted lustre-client [ 7090.657680] Lustre: lustre-MDT0000-mdc-ffff9f0dd12fe800: Connection to lustre-MDT0000 (at 192.168.203.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7093.019715] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7093.027978] LustreError: 260755:0:(file.c:6174:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [ 7093.116842] LustreError: 260774:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0dd12fe800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7093.118047] LustreError: MGC192.168.203.127@tcp: Connection to MGS (at 192.168.203.127@tcp) was lost; in progress operations using this service will fail [ 7093.122423] LustreError: 260774:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7093.138116] Lustre: Unmounted lustre-client [ 7103.644542] Key type lgssc unregistered [ 7103.755338] LNet: 261167:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7103.759306] LNetError: 261167:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7103.769254] LNet: Removed LNI 192.168.203.27@tcp [ 7103.998373] Key type .llcrypt unregistered [ 7103.999846] Key type ._llcrypt unregistered [ 7108.782171] Key type ._llcrypt registered [ 7108.783142] Key type .llcrypt registered [ 7109.235322] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 21:45:48 (1770691548) [ 7110.049182] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7110.080577] alg: No test for adler32 (adler32-zlib) [ 7110.926340] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 7111.002620] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 7112.583116] Key type lgssc registered [ 7112.925213] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7129.766220] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7130.982887] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7136.949402] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7148.527779] Lustre: Mounted lustre-client [ 7153.634931] Lustre: lustre-MDT0001-mdc-ffff9f0dd2fc4800: Connection to lustre-MDT0001 (at 192.168.203.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7156.255315] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7156.619729] LustreError: 264258:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0dd2fc4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7156.627688] LustreError: 264258:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7156.649130] Lustre: Unmounted lustre-client [ 7167.573502] Key type lgssc unregistered [ 7167.705529] LNet: 264650:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7167.707771] LNetError: 264650:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7167.715634] LNet: Removed LNI 192.168.203.27@tcp [ 7167.961855] Key type .llcrypt unregistered [ 7167.962974] Key type ._llcrypt unregistered [ 7173.429065] Key type ._llcrypt registered [ 7173.430154] Key type .llcrypt registered [ 7173.979220] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 21:46:52 (1770691612) [ 7179.899680] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7179.906203] alg: No test for adler32 (adler32-zlib) [ 7180.744346] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 7180.814516] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 7182.391176] Key type lgssc registered [ 7182.800047] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7219.868090] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7221.031842] Lustre: Mounted lustre-client [ 7237.091805] Lustre: lustre-MDT0001-mdc-ffff9f0dd370e800: Connection to lustre-MDT0001 (at 192.168.203.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7241.752046] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7252.007399] LustreError: 269982:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 7252.020994] Lustre: Unmounted lustre-client [ 7269.470431] Key type lgssc unregistered [ 7269.600563] LNet: 270382:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7269.605197] LNetError: 270382:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7269.616402] LNet: Removed LNI 192.168.203.27@tcp [ 7269.902628] Key type .llcrypt unregistered [ 7269.903858] Key type ._llcrypt unregistered [ 7275.201981] Key type ._llcrypt registered [ 7275.202847] Key type .llcrypt registered [ 7275.667227] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 21:48:34 (1770691714) [ 7276.196342] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [ 7276.745163] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 21:48:35 (1770691715) [ 7277.340481] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [ 7277.930358] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 21:48:36 (1770691716) [ 7278.460511] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [ 7278.981169] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 21:48:38 (1770691718) [ 7279.504355] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [ 7280.046291] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 21:48:39 (1770691719) [ 7280.551718] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [ 7281.131712] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 21:48:40 (1770691720) [ 7284.153315] Key type .llcrypt unregistered [ 7284.154297] Key type ._llcrypt unregistered [ 7286.436838] Key type ._llcrypt registered [ 7286.438433] Key type .llcrypt registered [ 7286.627824] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7286.643224] alg: No test for adler32 (adler32-zlib) [ 7287.515451] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 7287.607932] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 7289.199159] Key type lgssc registered [ 7289.642227] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7315.922901] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7317.118739] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7324.699348] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7331.313788] Lustre: Mounted lustre-client [ 7331.784941] LustreError: 275960:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0de0155800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7331.791763] LustreError: 275960:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7331.820182] Lustre: Unmounted lustre-client [ 7355.112505] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 21:49:54 (1770691794) [ 7365.373269] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7366.676250] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7383.601903] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7383.774340] Lustre: Mounted lustre-client [ 7384.134317] LustreError: 278070:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0dc8db0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7384.141653] LustreError: 278070:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7384.143989] LustreError: 278070:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 7384.170230] Lustre: Unmounted lustre-client [ 7413.679133] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 21:50:52 (1770691852) [ 7436.127313] LNet: 1 local NIs in recovery (showing 1): 192.168.203.27@tcp [ 8000.884775] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8002.056436] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8019.893260] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8076.561216] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 22:01:55 (1770692515) [ 8080.360909] Key type lgssc unregistered [ 8080.483702] LNet: 283615:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8080.487363] LNetError: 283615:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8080.496382] LNet: Removed LNI 192.168.203.27@tcp [ 8080.742350] Key type .llcrypt unregistered [ 8080.743639] Key type ._llcrypt unregistered [ 8149.971863] Key type ._llcrypt registered [ 8149.974416] Key type .llcrypt registered [ 8150.207700] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8150.220937] alg: No test for adler32 (adler32-zlib) [ 8151.100790] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 8151.192527] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 8152.783208] Key type lgssc registered [ 8153.166638] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8184.776717] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8186.072790] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8193.389443] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8221.056701] Key type lgssc unregistered [ 8221.177749] LNet: 287194:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8221.181883] LNetError: 287194:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8221.191095] LNet: Removed LNI 192.168.203.27@tcp [ 8221.439423] Key type .llcrypt unregistered [ 8221.440458] Key type ._llcrypt unregistered [ 8235.203455] Key type ._llcrypt registered [ 8235.204678] Key type .llcrypt registered [ 8235.715538] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 22:04:34 (1770692674) [ 8242.495267] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8242.551138] alg: No test for adler32 (adler32-zlib) [ 8243.412613] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 8243.501641] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 8245.079163] Key type lgssc registered [ 8245.489644] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8263.395690] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 22:05:02 (1770692702) [ 8267.533974] Key type lgssc unregistered [ 8267.652509] LNet: 290750:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8267.654815] LNetError: 290750:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8267.660832] LNet: Removed LNI 192.168.203.27@tcp [ 8267.904564] Key type .llcrypt unregistered [ 8267.905588] Key type ._llcrypt unregistered [ 8271.463399] Key type ._llcrypt registered [ 8271.466694] Key type .llcrypt registered [ 8271.652510] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8271.659674] alg: No test for adler32 (adler32-zlib) [ 8272.537242] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 8272.639235] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 8274.231165] Key type lgssc registered [ 8274.666907] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8295.990771] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8297.247296] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8304.780414] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8384.300070] LNet: Added LNI 192.168.203.27@tcp50 [8/256/0/180] [ 8385.117776] LNet: Removed LNI 192.168.203.27@tcp [ 8386.539788] Lustre: 295011:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff9f0dd2fc3000: config has no valid NIDs, force dynamic NIDs [ 8387.586038] Lustre: Mounted lustre-client [ 8387.608611] LustreError: 295027:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0dd2fc3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8387.614173] LustreError: 295027:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8387.633129] Lustre: Unmounted lustre-client [ 8406.345608] Key type lgssc unregistered [ 8406.513204] LNet: 295866:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8406.516124] LNetError: 295866:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8406.526187] LNet: Removed LNI 192.168.203.27@tcp50 [ 8406.756541] Key type .llcrypt unregistered [ 8406.757768] Key type ._llcrypt unregistered [ 8440.162151] Key type ._llcrypt registered [ 8440.163244] Key type .llcrypt registered [ 8440.606704] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 22:07:59 (1770692879) [ 8441.355153] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8441.407372] alg: No test for adler32 (adler32-zlib) [ 8442.275820] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 8442.361227] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 8443.943206] Key type lgssc registered [ 8444.312933] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8460.848952] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8461.891873] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8467.587460] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8468.743634] Lustre: Mounted lustre-client [ 8491.901337] Lustre: Mounted fs15246-client [ 8497.541763] LustreError: 299992:0:(lov_obd.c:783:lov_cleanup()) fs15246-clilov-ffff9f0dc93de000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8497.553963] LustreError: 299992:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 8497.573237] Lustre: Unmounted fs15246-client [ 8510.498454] LustreError: 300210:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0de110f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8510.502284] LustreError: 300210:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8510.504192] LustreError: 300210:0:(obd_class.h:479:obd_check_dev()) Skipped 3 previous similar messages [ 8510.523111] Lustre: Unmounted lustre-client [ 8527.947279] Key type lgssc unregistered [ 8528.073568] LNet: 300612:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8528.076401] LNetError: 300612:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8528.083701] LNet: Removed LNI 192.168.203.27@tcp [ 8528.324618] Key type .llcrypt unregistered [ 8528.326113] Key type ._llcrypt unregistered [ 8531.908065] Key type ._llcrypt registered [ 8531.909283] Key type .llcrypt registered [ 8532.370759] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 22:09:31 (1770692971) [ 8533.122102] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8533.131778] alg: No test for adler32 (adler32-zlib) [ 8533.994670] Lustre: Lustre: Build Version: 2.17.50_65_g69bb343 [ 8534.081595] LNet: Added LNI 192.168.203.27@tcp [8/256/0/180] [ 8535.663187] Key type lgssc registered [ 8536.034042] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8582.606264] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8583.813485] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8592.991738] Lustre: DEBUG MARKER: oleg327-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8642.368919] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 8351 sec ========== 22:11:21 (1770693081) [ 8642.911141] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 22:11:21 (1770693081) === [ 8644.016837] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 22:11:23 (1770693083) === [ 8655.647669] Key type lgssc unregistered [ 8655.760576] LNet: 309750:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8655.763694] LNetError: 309750:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8655.772918] LNet: Removed LNI 192.168.203.27@tcp [ 8656.064609] Key type .llcrypt unregistered [ 8656.065615] Key type ._llcrypt unregistered