[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.16.2-1.fc38 04/01/2014 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 501394468 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f5b50-0x000f5b5f] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F5970 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE1BB7 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE1A53 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 001A13 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE1AC7 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE1B57 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE1B8F 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe1a53-0xbffe1ac6] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe1a52] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe1ac7-0xbffe1b56] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe1b57-0xbffe1b8e] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe1b8f-0xbffe1bb6] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001013] APIC: Switch to symmetric I/O mode setup [ 0.003260] x2apic enabled [ 0.004007] Switched APIC routing to physical x2apic. [ 0.005012] kvm-guest: setup PV IPIs [ 0.007000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.007000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.007025] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.008012] pid_max: default: 32768 minimum: 301 [ 0.010123] LSM: Security Framework initializing [ 0.011060] Yama: becoming mindful. [ 0.012038] SELinux: Initializing. [ 0.013070] *** VALIDATE selinux *** [ 0.021704] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026054] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.028153] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029110] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030118] *** VALIDATE tmpfs *** [ 0.031460] *** VALIDATE proc *** [ 0.033231] *** VALIDATE cgroup *** [ 0.034008] *** VALIDATE cgroup2 *** [ 0.035304] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.037151] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.038006] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.039028] Spectre V2 : User space: Vulnerable [ 0.040010] Speculative Store Bypass: Vulnerable [ 0.044773] debug: unmapping init [mem 0xffffffffae859000-0xffffffffae860fff] [ 0.046000] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.046664] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.047024] ... version: 2 [ 0.048015] ... bit width: 48 [ 0.049013] ... generic registers: 4 [ 0.050011] ... value mask: 0000ffffffffffff [ 0.051014] ... max period: 00007fffffffffff [ 0.052016] ... fixed-purpose events: 3 [ 0.053014] ... event mask: 000000070000000f [ 0.055270] rcu: Hierarchical SRCU implementation. [ 0.057482] smp: Bringing up secondary CPUs ... [ 0.058607] x86: Booting SMP configuration: [ 0.059026] .... node #0, CPUs: #1 #2 #3 [ 0.067735] smp: Brought up 1 node, 4 CPUs [ 0.069016] smpboot: Max logical packages: 1 [ 0.070013] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.103048] node 0 deferred pages initialised in 30ms [ 0.106130] devtmpfs: initialized [ 0.107000] x86/mm: Memory block size: 128MB [ 0.109803] gcov: version magic: 0x41383552 [ 0.113106] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.114086] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.115554] pinctrl core: initialized pinctrl subsystem [ 0.116117] [ 0.116421] ************************************************************* [ 0.117011] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.118014] ** ** [ 0.119015] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.120012] ** ** [ 0.121014] ** This means that this kernel is built to expose internal ** [ 0.122014] ** IOMMU data structures, which may compromise security on ** [ 0.123022] ** your system. ** [ 0.124019] ** ** [ 0.125023] ** If you see this message and you are not debugging the ** [ 0.126020] ** kernel, report this immediately to your vendor! ** [ 0.127020] ** ** [ 0.128020] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.129020] ************************************************************* [ 0.132950] NET: Registered protocol family 16 [ 0.133475] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.134668] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.136097] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.139044] cpuidle: using governor menu [ 0.149831] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.154502] PCI: Using configuration type 1 for base access [ 0.156124] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.166101] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.168026] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.171146] cryptd: max_cpu_qlen set to 1000 [ 0.172268] ACPI: Added _OSI(Module Device) [ 0.174014] ACPI: Added _OSI(Processor Device) [ 0.175009] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.176013] ACPI: Added _OSI(Processor Aggregator Device) [ 0.180000] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.185657] ACPI: Interpreter enabled [ 0.187070] ACPI: PM: (supports S0 S3 S4 S5) [ 0.189018] ACPI: Using IOAPIC for interrupt routing [ 0.190082] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.193337] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.199981] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.201036] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.203020] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.204078] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.207000] acpiphp: Slot [2] registered [ 0.207000] acpiphp: Slot [3] registered [ 0.207130] acpiphp: Slot [4] registered [ 0.208000] acpiphp: Slot [5] registered [ 0.209160] acpiphp: Slot [6] registered [ 0.211143] acpiphp: Slot [7] registered [ 0.212099] acpiphp: Slot [8] registered [ 0.213085] acpiphp: Slot [9] registered [ 0.215089] acpiphp: Slot [10] registered [ 0.216091] acpiphp: Slot [11] registered [ 0.218085] acpiphp: Slot [12] registered [ 0.219142] acpiphp: Slot [13] registered [ 0.221102] acpiphp: Slot [14] registered [ 0.222067] acpiphp: Slot [15] registered [ 0.223089] acpiphp: Slot [16] registered [ 0.224085] acpiphp: Slot [17] registered [ 0.225081] acpiphp: Slot [18] registered [ 0.226000] acpiphp: Slot [19] registered [ 0.226000] acpiphp: Slot [20] registered [ 0.227090] acpiphp: Slot [21] registered [ 0.228057] acpiphp: Slot [22] registered [ 0.229000] acpiphp: Slot [23] registered [ 0.229000] acpiphp: Slot [24] registered [ 0.230059] acpiphp: Slot [25] registered [ 0.231116] acpiphp: Slot [26] registered [ 0.233054] acpiphp: Slot [27] registered [ 0.234080] acpiphp: Slot [28] registered [ 0.235074] acpiphp: Slot [29] registered [ 0.237075] acpiphp: Slot [30] registered [ 0.238067] acpiphp: Slot [31] registered [ 0.239027] PCI host bridge to bus 0000:00 [ 0.239953] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.241015] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.243020] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.246021] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.248022] pci_bus 0000:00: root bus resource [mem 0x180000000-0x1ffffffff window] [ 0.250028] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.252171] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.255000] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.259566] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.265483] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.270302] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.272015] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.274014] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.277015] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.279496] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.282748] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.300046] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.302072] pci 0000:00:01.3: quirk_piix4_acpi+0x0/0x1e0 took 19531 usecs [ 0.305484] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.308901] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.320000] pci 0000:00:02.0: reg 0x20: [mem 0xfebf4000-0xfebf7fff 64bit pref] [ 0.324720] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.331000] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.340023] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.353017] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.390018] pci 0000:00:05.0: reg 0x20: [mem 0xfebf8000-0xfebfbfff 64bit pref] [ 0.406839] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.416015] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.421013] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.443016] pci 0000:00:06.0: reg 0x20: [mem 0xfebfc000-0xfebfffff 64bit pref] [ 0.457408] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.461458] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.468366] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.470430] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.473243] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.477029] iommu: Default domain type: Passthrough [ 0.478417] SCSI subsystem initialized [ 0.480145] ACPI: bus type USB registered [ 0.481137] usbcore: registered new interface driver usbfs [ 0.483129] usbcore: registered new interface driver hub [ 0.488634] usbcore: registered new device driver usb [ 0.490316] pps_core: LinuxPPS API ver. 1 registered [ 0.492010] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.496068] PTP clock support registered [ 0.497175] EDAC MC: Ver: 3.0.0 [ 0.499107] PCI: Using ACPI for IRQ routing [ 0.501895] NetLabel: Initializing [ 0.502009] NetLabel: domain hash size = 128 [ 0.503010] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.505079] NetLabel: unlabeled traffic allowed by default [ 0.507484] vgaarb: loaded [ 0.509823] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.510000] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.515000] clocksource: Switched to clocksource kvm-clock [ 0.622195] VFS: Disk quotas dquot_6.6.0 [ 0.623698] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.626285] *** VALIDATE ramfs *** [ 0.627563] *** VALIDATE hugetlbfs *** [ 0.629147] pnp: PnP ACPI init [ 0.632155] pnp: PnP ACPI: found 6 devices [ 0.650112] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.653200] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.655031] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.657250] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.659562] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.661969] pci_bus 0000:00: resource 8 [mem 0x180000000-0x1ffffffff window] [ 0.664819] NET: Registered protocol family 2 [ 0.667340] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.672562] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.676673] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.681789] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.685084] TCP: Hash tables configured (established 65536 bind 65536) [ 0.687683] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.690239] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.692985] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.695021] NET: Registered protocol family 1 [ 0.697630] RPC: Registered named UNIX socket transport module. [ 0.699756] RPC: Registered udp transport module. [ 0.701272] RPC: Registered tcp transport module. [ 0.702729] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.704960] NET: Registered protocol family 44 [ 0.706613] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.708718] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.710774] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.712562] PCI: CLS 0 bytes, default 64 [ 0.713868] Unpacking initramfs... [ 2.474630] debug: unmapping init [mem 0xffff9c0b7cc64000-0xffff9c0b7ffcffff] [ 2.481240] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.484410] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.488668] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 3.114691] Initialise system trusted keyrings [ 3.116902] Key type blacklist registered [ 3.119653] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 3.130837] zbud: loaded [ 3.136174] *** VALIDATE nfs *** [ 3.136969] *** VALIDATE nfs4 *** [ 3.138729] pstore: using deflate compression [ 3.145638] Platform Keyring initialized [ 3.367902] NET: Registered protocol family 38 [ 3.369430] Key type asymmetric registered [ 3.370538] Asymmetric key parser 'x509' registered [ 3.376297] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.386786] io scheduler mq-deadline registered [ 3.388042] io scheduler kyber registered [ 3.389222] io scheduler bfq registered [ 3.390629] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.393389] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.395643] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.399597] ACPI: Power Button [PWRF] [ 3.520361] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.641964] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.808893] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.846899] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.889636] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.898390] Non-volatile memory driver v1.3 [ 3.900339] Linux agpgart interface v0.103 [ 3.948264] virtio_blk virtio1: [vda] 133928 512-byte logical blocks (68.6 MB/65.4 MiB) [ 3.950922] vda: detected capacity change from 0 to 68571136 [ 3.967929] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.973328] vdb: detected capacity change from 0 to 1073741824 [ 3.984959] libphy: Fixed MDIO Bus: probed [ 3.996876] usbcore: registered new interface driver usbserial_generic [ 4.000238] usbserial: USB Serial support registered for generic [ 4.002459] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 4.007199] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 4.008423] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 4.010144] mousedev: PS/2 mouse device common for all mice [ 4.012789] rtc_cmos 00:05: RTC can wake from S4 [ 4.017223] rtc_cmos 00:05: registered as rtc0 [ 4.019282] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 4.023375] intel_pstate: CPU model not supported [ 4.027151] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 4.031568] hid: raw HID events driver (C) Jiri Kosina [ 4.033784] usbcore: registered new interface driver usbhid [ 4.035897] usbhid: USB HID core driver [ 4.037456] drop_monitor: Initializing network drop monitor service [ 4.039710] Initializing XFRM netlink socket [ 4.042479] NET: Registered protocol family 10 [ 4.046177] Segment Routing with IPv6 [ 4.048080] NET: Registered protocol family 17 [ 4.050095] mpls_gso: MPLS GSO support [ 4.057204] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 4.063994] RAS: Correctable Errors collector initialized. [ 4.068928] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 4.072051] AVX version of gcm_enc/dec engaged. [ 4.073997] AES CTR mode by8 optimization enabled [ 4.201626] sched_clock: Marking stable (4201609394, 0)->(5295875372, -1094265978) [ 4.205888] registered taskstats version 1 [ 4.208267] Loading compiled-in X.509 certificates [ 4.210982] zswap: loaded using pool lzo/zbud [ 4.287132] Key type big_key registered [ 4.305567] Key type encrypted registered [ 4.307544] ima: No TPM chip found, activating TPM-bypass! [ 4.309874] ima: Allocated hash algorithm: sha1 [ 4.311287] ima: No architecture policies found [ 4.312611] evm: Initialising EVM extended attributes: [ 4.314402] evm: security.selinux [ 4.315780] evm: security.ima [ 4.316911] evm: security.capability [ 4.318058] evm: HMAC attrs: 0x1 [ 4.320253] rtc_cmos 00:05: setting system clock to 2025-10-24 05:38:24 UTC (1761284304) [ 4.326047] debug: unmapping init [mem 0xffffffffaf803000-0xffffffffaf9fffff] [ 4.328928] debug: unmapping init [mem 0xffffffffae582000-0xffffffffae858fff] [ 4.340153] Write protecting the kernel read-only data: 28672k [ 4.343187] debug: unmapping init [mem 0xffffffffacc03000-0xffffffffacdfffff] [ 4.345769] debug: unmapping init [mem 0xffffffffad514000-0xffffffffad5fffff] [ 4.394851] 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) [ 4.403852] systemd[1]: Detected virtualization kvm. [ 4.405487] systemd[1]: Detected architecture x86-64. [ 4.407107] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 4.441593] systemd[1]: No hostname configured. [ 4.443643] systemd[1]: Set hostname to . [ 4.449882] random: systemd: uninitialized urandom read (16 bytes read) [ 4.454672] systemd[1]: Initializing machine ID from random generator. [ 4.681867] random: systemd: uninitialized urandom read (16 bytes read) [ 4.684612] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ 4.694386] random: systemd: uninitialized urandom read (16 bytes read) [ 4.707436] systemd[1]: Starting Setup Virtual Console... Starting Setup Virtual Console... [ 4.727019] systemd[1]: Starting Create list of required static device nodes for the current kernel... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Timers. [ OK ] Listening on udev Control Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. Starting Apply Kernel Variables... [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Reached target Swap. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Reached target Sockets. [ OK ] Started Memstrack Anylazing Service. [ OK ] Reached target Slices. [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Paths. [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 5.954390] device-mapper: uevent: version 1.0.3 [ 5.957476] 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. [ 7.493393] virtio_net virtio0 ens2: renamed from eth0 [ 7.969816] scsi host0: ata_piix [ 8.057277] scsi host1: ata_piix [ 8.058668] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 8.073365] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 12.879630] random: crng init done [ 12.880834] random: 7 urandom warning(s) missed due to ratelimiting [ 14.399765] dracut-initqueue[587]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 16.071537] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped target Basic System. [ OK ] Stopped target System Initialization. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Paths. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 17.771501] printk: systemd: 26 output lines suppressed due to ratelimiting [ 18.165910] SELinux: Disabled at runtime. [ 18.250455] 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) [ 18.266863] systemd[1]: Detected virtualization kvm. [ 18.271702] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 19.238399] systemd[1]: initrd-switch-root.service: Succeeded. [ 19.243406] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 19.251079] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 19.256156] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 19.260720] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 19.282862] systemd[1]: Starting Journal Service... Starting Journal Service... [ 19.313766] systemd[1]: Activating swap /dev/disk/by-label/SWAP... Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on udev Kernel Socket. [ 19.394325] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Listening on Process Core Dump Socket. Starting Remount Root and Kernel File Systems... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. Mounting POSIX Message Queue File System... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Created slice system-getty.slice. Mounting Huge Pages File System... [ OK ] Reached target rpc_pipefs.target. [ OK ] Started Dispatch Password Requests to Console Directory Watch. Starting Apply Kernel Variables... [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Reached target Paths. [ OK ] Stopped target Initrd Root File System. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Created slice system-serial\x2dgetty.slice. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Mounting Kernel Debug File System... [ OK ] Started Journal Service. [ OK ] Activated swap /dev/disk/by-label/SWAP. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Huge Pages File System. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted Kernel Debug File System. Starting Create Static Device Nodes in /dev... Starting Configure read-only root support... [ 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. [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 20.508279] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 21.304184] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 21.336731] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 21.736642] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 21.830083] EDAC sbridge: Ver: 1.1.2 [ 23.878799] Key type dns_resolver registered [ 24.223713] NFS: Registering the id_resolver key type [ 24.225903] Key type id_resolver registered [ 24.227538] 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 RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Restore /run/initramfs on shutdown... Starting Login Service... [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target sshd-keygen.target. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started dnf makecache --timer. [ OK ] Started irqbalance daemon. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... [ OK ] Started Login Service. Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ 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 System Logging Service... Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg302-client login: [ 55.714582] libcfs: loading out-of-tree module taints kernel. [ 55.761868] Key type ._llcrypt registered [ 55.767064] Key type .llcrypt registered [ 56.008038] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 56.014651] alg: No test for adler32 (adler32-zlib) [ 57.029859] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 57.361580] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 58.975270] Key type lgssc registered [ 59.542259] Lustre: Echo OBD driver; http://www.lustre.org/ [ 124.168563] Lustre: Mounted lustre-client [ 126.572807] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 137.443081] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing check_logdir /tmp/testlogs/ [ 138.999449] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing yml_node [ 140.871969] Lustre: DEBUG MARKER: Client: 2.16.59.49 [ 141.933231] Lustre: DEBUG MARKER: MDS: 2.16.59.49 [ 143.024463] Lustre: DEBUG MARKER: OSS: 2.16.59.49 [ 143.761540] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Fri Oct 24 01:40:43 EDT 2025 [ 144.863756] Lustre: lustre-OST0000-osc-ffff9c0bc629c000: disconnect after 20s idle [ 151.384975] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 152.165862] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 152.952954] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 163.239655] LustreError: 8975:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 163.258067] Lustre: Unmounted lustre-client [ 217.535019] hrtimer: interrupt took 7310651 ns [ 234.964234] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 241.070884] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 268.459790] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 301.716728] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 01:43:20 (1761284600) [ 303.572290] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 304.869978] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 01:43:23 (1761284603) [ 327.804849] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 330.595778] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 344.143991] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 344.718581] Lustre: Mounted lustre-client [ 352.028771] LustreError: 14413:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bc3580800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 352.060515] LustreError: 14413:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 352.064834] LustreError: 14413:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 352.126178] Lustre: Unmounted lustre-client [ 378.511827] Key type lgssc unregistered [ 378.750227] LNet: 14822:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 378.763176] LNetError: 14822:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 378.792202] LNet: Removed LNI 192.168.203.2@tcp [ 379.394226] Key type .llcrypt unregistered [ 379.396208] Key type ._llcrypt unregistered [ 390.971516] Key type ._llcrypt registered [ 390.978047] Key type .llcrypt registered [ 391.871331] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 01:44:51 (1761284691) [ 393.045194] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 393.070504] alg: No test for adler32 (adler32-zlib) [ 394.107856] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 394.303501] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 395.999158] Key type lgssc registered [ 396.867842] Lustre: Echo OBD driver; http://www.lustre.org/ [ 468.933618] Lustre: Mounted lustre-client [ 556.708388] LustreError: 18130:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bc3580800: lov tgt 1990 not cleaned! deathrow=0, lovrc=1 [ 556.735949] LustreError: 18130:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 556.793250] Lustre: Unmounted lustre-client [ 592.679266] Key type lgssc unregistered [ 593.008225] LNet: 18594:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 593.014245] LNetError: 18594:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 594.024421] LNet: Removed LNI 192.168.203.2@tcp [ 594.688889] Key type .llcrypt unregistered [ 594.693777] Key type ._llcrypt unregistered [ 603.551587] Key type ._llcrypt registered [ 603.552860] Key type .llcrypt registered [ 604.870388] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 01:48:23 (1761284903) [ 607.183563] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 607.203951] alg: No test for adler32 (adler32-zlib) [ 608.277350] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 608.635396] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 610.303325] Key type lgssc registered [ 611.585439] Lustre: Echo OBD driver; http://www.lustre.org/ [ 656.795906] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 660.341379] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 679.075808] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 681.769609] Lustre: Mounted lustre-client [ 691.287741] LustreError: 21698:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bc629d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 691.320468] LustreError: 21698:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 691.416359] Lustre: Unmounted lustre-client [ 718.830880] Key type lgssc unregistered [ 719.115736] LNet: 22103:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 719.124488] LNetError: 22103:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 719.148668] LNet: Removed LNI 192.168.203.2@tcp [ 719.886725] Key type .llcrypt unregistered [ 719.905700] Key type ._llcrypt unregistered [ 730.226235] Key type ._llcrypt registered [ 730.227883] Key type .llcrypt registered [ 731.685177] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 01:50:30 (1761285030) [ 734.508374] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 734.542111] alg: No test for adler32 (adler32-zlib) [ 735.642655] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 735.844769] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 737.535172] Key type lgssc registered [ 738.830977] Lustre: Echo OBD driver; http://www.lustre.org/ [ 783.618179] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 787.548127] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 806.013213] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 812.100208] Lustre: Mounted lustre-client [ 817.625445] LustreError: 24806:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bd0bf1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 817.654470] LustreError: 24806:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 817.711947] Lustre: Unmounted lustre-client [ 845.847672] Key type lgssc unregistered [ 846.079521] LNet: 25210:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 846.086075] LNetError: 25210:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 846.112503] LNet: Removed LNI 192.168.203.2@tcp [ 846.809516] Key type .llcrypt unregistered [ 846.811064] Key type ._llcrypt unregistered [ 862.923927] Key type ._llcrypt registered [ 862.925640] Key type .llcrypt registered [ 864.282566] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 01:52:42 (1761285162) [ 866.632161] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 866.662327] alg: No test for adler32 (adler32-zlib) [ 867.790133] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 867.991436] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 869.703242] Key type lgssc registered [ 871.118925] Lustre: Echo OBD driver; http://www.lustre.org/ [ 916.636658] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 919.879567] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 935.579551] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 942.113545] Lustre: Mounted lustre-client [ 947.175953] Lustre: lustre-MDT0000-mdc-ffff9c0bc9953800: Connection to lustre-MDT0000 (at 192.168.203.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 957.421688] Lustre: lustre-MDT0001-mdc-ffff9c0bc9953800: Connection to lustre-MDT0001 (at 192.168.203.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 964.920561] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 973.791150] Lustre: 26166:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761285257/real 1761285257] req@00000000a30d6689 x1846841356327040/t0(0) o400->MGC192.168.203.102@tcp@192.168.203.102@tcp:26/25 lens 224/224 e 0 to 1 dl 1761285273 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 973.804571] LustreError: MGC192.168.203.102@tcp: Connection to MGS (at 192.168.203.102@tcp) was lost; in progress operations using this service will fail [ 975.223390] LustreError: 28388:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 975.264531] Lustre: Unmounted lustre-client [ 993.427597] Key type lgssc unregistered [ 993.694303] LNet: 28780:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 993.701116] LNetError: 28780:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 994.727522] LNet: Removed LNI 192.168.203.2@tcp [ 995.371390] Key type .llcrypt unregistered [ 995.375784] Key type ._llcrypt unregistered [ 1007.223769] Key type ._llcrypt registered [ 1007.225412] Key type .llcrypt registered [ 1008.164691] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 01:55:07 (1761285307) [ 1010.065984] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1010.121193] alg: No test for adler32 (adler32-zlib) [ 1011.154183] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 1011.378334] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 1013.015206] Key type lgssc registered [ 1013.831386] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1051.706713] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1055.223790] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1071.178859] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1081.907118] Lustre: Mounted lustre-client [ 1086.958307] Lustre: lustre-OST0000-osc-ffff9c0bd1c72800: Connection to lustre-OST0000 (at 192.168.203.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1093.643387] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1103.917625] LustreError: 31902:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1103.969832] Lustre: Unmounted lustre-client [ 1134.781837] Key type lgssc unregistered [ 1134.980380] LNet: 32303:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1134.987943] LNetError: 32303:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1135.001714] LNet: Removed LNI 192.168.203.2@tcp [ 1135.376179] Key type .llcrypt unregistered [ 1135.378230] Key type ._llcrypt unregistered [ 1146.176423] Key type ._llcrypt registered [ 1146.177809] Key type .llcrypt registered [ 1147.201948] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 01:57:26 (1761285446) [ 1148.942589] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1149.000245] alg: No test for adler32 (adler32-zlib) [ 1150.088641] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 1150.295461] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 1151.959302] Key type lgssc registered [ 1152.834871] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1188.987695] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1191.689770] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1205.109217] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1222.180131] Lustre: Mounted lustre-client [ 1225.002156] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1227.702262] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1238.742807] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2025-10-24 1h58m57s [ 1240.422347] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1242.605985] Lustre: lustre-MDT0000-mdc-ffff9c0bd1d08000: Connection to lustre-MDT0000 (at 192.168.203.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1249.474718] LustreError: 36003:0:(lmv_obd.c:1450:lmv_statfs()) lustre-MDT0000-mdc-ffff9c0bd1d08000: can't stat MDS #0: rc = -19 [ 1249.492277] LustreError: 36003:0:(mdc_request.c:2165:mdc_quotactl()) lustre-MDT0000-mdc-ffff9c0bd1d08000: ptlrpc_queue_wait failed: rc = -11 [ 1250.510932] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1260.004692] LustreError: MGC192.168.203.102@tcp: Connection to MGS (at 192.168.203.102@tcp) was lost; in progress operations using this service will fail [ 1260.033690] Lustre: Evicted from MGS (at 192.168.203.102@tcp) after server handle changed from 0xf6d61d6b6710b96d to 0xf6d61d6b6710bd09 [ 1260.047865] Lustre: MGC192.168.203.102@tcp: Connection restored to 192.168.203.102@tcp (at 192.168.203.102@tcp) [ 1261.100054] LustreError: lustre-MDT0000-mdc-ffff9c0bd1d08000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1261.118152] Lustre: lustre-MDT0000-mdc-ffff9c0bd1d08000: Connection restored to 192.168.203.102@tcp (at 192.168.203.102@tcp) [ 1262.850606] Lustre: DEBUG MARKER: Wait for df (36003) ... [ 1264.303218] Lustre: DEBUG MARKER: done [ 1265.213659] LustreError: 36614:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bd1d08000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1265.221262] LustreError: 36614:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1265.263466] Lustre: Unmounted lustre-client [ 1289.817746] Key type lgssc unregistered [ 1289.986984] LNet: 37017:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1289.992085] LNetError: 37017:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1290.006193] LNet: Removed LNI 192.168.203.2@tcp [ 1290.502195] Key type .llcrypt unregistered [ 1290.503746] Key type ._llcrypt unregistered [ 1329.388764] Key type ._llcrypt registered [ 1329.390186] Key type .llcrypt registered [ 1329.949069] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1329.972072] alg: No test for adler32 (adler32-zlib) [ 1331.017769] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 1331.218401] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 1332.935168] Key type lgssc registered [ 1333.898419] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1398.785467] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1402.147354] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1419.727824] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1466.215963] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 02:02:45 (1761285765) [ 1488.918996] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1492.082530] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1508.670587] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1519.688030] Lustre: Mounted lustre-client [ 1524.252589] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2025-10-24 2h03m43s [ 1525.626410] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1530.526129] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1532.184622] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1552.351161] Lustre: 43905:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761285832/real 1761285832] req@00000000628f28c8 x1846841841817344/t0(0) o101->lustre-MDT0000-mdc-ffff9c0bc7d90000@192.168.203.102@tcp:12/10 lens 576/1152 e 0 to 1 dl 1761285852 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:0 [ 1552.389685] Lustre: lustre-MDT0000-mdc-ffff9c0bc7d90000: Connection to lustre-MDT0000 (at 192.168.203.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1552.425769] Lustre: lustre-MDT0000-mdc-ffff9c0bc7d90000: Connection restored to 192.168.203.102@tcp (at 192.168.203.102@tcp) [ 1573.763765] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1576.531629] Lustre: DEBUG MARKER: done [ 1579.454731] LustreError: 44385:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bc7d90000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1579.476512] LustreError: 44385:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1579.539320] Lustre: Unmounted lustre-client [ 1606.581440] Key type lgssc unregistered [ 1606.872659] LNet: 44788:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1606.879191] LNetError: 44788:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1606.894594] LNet: Removed LNI 192.168.203.2@tcp [ 1607.619599] Key type .llcrypt unregistered [ 1607.621754] Key type ._llcrypt unregistered [ 1645.093151] Key type ._llcrypt registered [ 1645.095223] Key type .llcrypt registered [ 1645.553895] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1645.599564] alg: No test for adler32 (adler32-zlib) [ 1646.582637] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 1646.778188] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 1648.399177] Key type lgssc registered [ 1649.384213] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1713.721910] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1717.566204] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1738.291531] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1774.567904] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 02:07:53 (1761286073) [ 1776.164858] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1777.880957] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 02:07:56 (1761286076) [ 1798.873633] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 02:08:17 (1761286097) [ 1822.754900] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1825.340908] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1839.648888] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1850.439556] Lustre: Mounted lustre-client [ 1853.662514] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 1856.573230] LustreError: 52622:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bd151f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1856.599865] LustreError: 52622:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1856.662895] Lustre: Unmounted lustre-client [ 1869.846951] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 1914.066712] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1917.458339] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1918.146694] Lustre: Mounted lustre-client [ 1921.171751] LustreError: 53882:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0be0330000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1921.218846] LustreError: 53882:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1921.222042] LustreError: 53882:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 1921.289153] Lustre: Unmounted lustre-client [ 1974.039734] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1977.039288] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1977.533588] Lustre: Mounted lustre-client [ 1980.609899] LustreError: 55004:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bc5a98000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1980.634351] LustreError: 55004:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1980.640790] LustreError: 55004:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 1980.722581] Lustre: Unmounted lustre-client [ 1996.347828] Lustre: DEBUG MARKER: files compared the same [ 2018.950992] Key type lgssc unregistered [ 2019.353239] LNet: 55708:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2019.365735] LNetError: 55708:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2019.398887] LNet: Removed LNI 192.168.203.2@tcp [ 2020.099957] Key type .llcrypt unregistered [ 2020.101568] Key type ._llcrypt unregistered [ 2035.220581] Key type ._llcrypt registered [ 2035.226550] Key type .llcrypt registered [ 2036.504403] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 02:12:15 (1761286335) [ 2038.528500] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2038.560783] alg: No test for adler32 (adler32-zlib) [ 2039.739078] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 2039.917442] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 2041.575498] Key type lgssc registered [ 2042.836678] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2086.402540] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2089.461990] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2105.201696] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2112.051256] Lustre: Mounted lustre-client [ 2114.995374] LustreError: 58802:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bd1518800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2115.010908] LustreError: 58802:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2115.058052] Lustre: Unmounted lustre-client [ 2142.648772] Key type lgssc unregistered [ 2142.973481] LNet: 59204:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2142.989358] LNetError: 59204:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2143.016799] LNet: Removed LNI 192.168.203.2@tcp [ 2143.806239] Key type .llcrypt unregistered [ 2143.807847] Key type ._llcrypt unregistered [ 2157.233444] Key type ._llcrypt registered [ 2157.234714] Key type .llcrypt registered [ 2158.423796] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 02:14:17 (1761286457) [ 2160.539830] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2160.591982] alg: No test for adler32 (adler32-zlib) [ 2161.636564] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 2161.858309] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 2163.543226] Key type lgssc registered [ 2164.878817] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2239.106118] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2263.124413] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2265.246922] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2290.314888] Key type lgssc unregistered [ 2290.654481] LNet: 62593:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2290.667878] LNetError: 62593:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2290.689845] LNet: Removed LNI 192.168.203.2@tcp [ 2291.364922] Key type .llcrypt unregistered [ 2291.366715] Key type ._llcrypt unregistered [ 2305.136435] Key type ._llcrypt registered [ 2305.138041] Key type .llcrypt registered [ 2306.560647] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 02:16:45 (1761286605) [ 2309.614126] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2309.634105] alg: No test for adler32 (adler32-zlib) [ 2310.646547] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 2310.846890] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 2312.487209] Key type lgssc registered [ 2313.511362] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2376.476091] Lustre: Mounted lustre-client [ 2383.510409] LustreError: 65081:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bc68bc800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2383.546690] LustreError: 65081:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2383.601772] Lustre: Unmounted lustre-client [ 2425.869406] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 02:18:44 (1761286724) [ 2525.733341] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2545.706277] Lustre: Mounted lustre-client [ 2552.443628] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2552.447617] LustreError: 69041:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff9c0bc8707800: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 2552.461852] Lustre: 63577:0:(llite_lib.c:4300:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.203.102@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 2552.477399] LustreError: 69041:0:(ldlm_resource.c:981:ldlm_resource_complain()) lustre-OST0000-osc-ffff9c0bc8707800: namespace resource [0x240000401:0x2:0x0].0x0 (00000000cbb2cfd4) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 2562.733603] LustreError: 69041:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2562.736079] LustreError: 69041:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 2562.784855] Lustre: Unmounted lustre-client [ 2602.041458] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 02:21:41 (1761286901) [ 2612.835575] Key type lgssc unregistered [ 2613.085347] LNet: 70250:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2613.090167] LNetError: 70250:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2613.111080] LNet: Removed LNI 192.168.203.2@tcp [ 2613.952601] Key type .llcrypt unregistered [ 2613.956775] Key type ._llcrypt unregistered [ 2625.517968] Key type ._llcrypt registered [ 2625.560332] Key type .llcrypt registered [ 2625.833996] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2625.841765] alg: No test for adler32 (adler32-zlib) [ 2626.919736] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 2627.119892] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 2628.799150] Key type lgssc registered [ 2629.991131] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2668.137845] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2671.290457] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2689.133518] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2700.351725] Lustre: Mounted lustre-client [ 2703.125541] LustreError: 72781:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bc802d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2703.148684] LustreError: 72781:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2703.231594] Lustre: Unmounted lustre-client [ 2729.593330] Key type lgssc unregistered [ 2729.836914] LNet: 73184:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2729.842053] LNetError: 73184:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2729.856265] LNet: Removed LNI 192.168.203.2@tcp [ 2730.529066] Key type .llcrypt unregistered [ 2730.530605] Key type ._llcrypt unregistered [ 2739.488610] Key type ._llcrypt registered [ 2739.490250] Key type .llcrypt registered [ 2739.804356] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2739.826899] alg: No test for adler32 (adler32-zlib) [ 2740.824886] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 2741.095338] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 2742.752291] Key type lgssc registered [ 2743.902285] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2832.855683] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2835.777662] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2851.063469] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2863.125876] Lustre: Mounted lustre-client [ 2864.585059] LustreError: 76120:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bc8701000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2864.601083] LustreError: 76120:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2864.648172] Lustre: Unmounted lustre-client [ 2888.484939] Key type lgssc unregistered [ 2888.679622] LNet: 76523:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2888.689696] LNetError: 76523:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2889.703410] LNet: Removed LNI 192.168.203.2@tcp [ 2890.250280] Key type .llcrypt unregistered [ 2890.252025] Key type ._llcrypt unregistered [ 2901.256492] Key type ._llcrypt registered [ 2901.257864] Key type .llcrypt registered [ 2902.285627] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 02:26:41 (1761287201) [ 2903.844123] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2903.909134] alg: No test for adler32 (adler32-zlib) [ 2904.932452] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 2905.127875] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 2906.775153] Key type lgssc registered [ 2907.614828] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2942.894806] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2946.297735] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2961.756483] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2972.703796] Lustre: Mounted lustre-client [ 2977.672288] LustreError: 79643:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bd1518800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2977.698505] LustreError: 79643:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2977.744240] Lustre: Unmounted lustre-client [ 2978.148410] Lustre: Mounted lustre-client [ 2980.994888] LustreError: 79718:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bc68bb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2981.024316] LustreError: 79718:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2981.027080] LustreError: 79718:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 2981.074161] Lustre: Unmounted lustre-client [ 3012.456451] Key type lgssc unregistered [ 3012.696888] LNet: 80120:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3012.713305] LNetError: 80120:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3012.738779] LNet: Removed LNI 192.168.203.2@tcp [ 3013.377784] Key type .llcrypt unregistered [ 3013.379956] Key type ._llcrypt unregistered [ 3024.338287] Key type ._llcrypt registered [ 3024.340038] Key type .llcrypt registered [ 3024.882266] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3024.892179] alg: No test for adler32 (adler32-zlib) [ 3025.977019] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 3026.234397] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 3027.883945] Key type lgssc registered [ 3029.226341] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3073.885136] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3077.065411] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3096.212448] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3098.835309] Lustre: Mounted lustre-client [ 3102.419318] LustreError: 82658:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bc8704000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3102.457830] LustreError: 82658:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3102.514282] Lustre: Unmounted lustre-client [ 3138.173625] Key type lgssc unregistered [ 3138.447140] LNet: 83062:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3138.461148] LNetError: 83062:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3138.494590] LNet: Removed LNI 192.168.203.2@tcp [ 3139.254651] Key type .llcrypt unregistered [ 3139.258128] Key type ._llcrypt unregistered [ 3150.957961] Key type ._llcrypt registered [ 3150.961107] Key type .llcrypt registered [ 3152.127732] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 02:30:51 (1761287451) [ 3154.786232] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3155.015045] alg: No test for adler32 (adler32-zlib) [ 3156.157243] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 3156.404232] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 3158.136327] Key type lgssc registered [ 3159.512974] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3201.557590] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3204.741843] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3221.111740] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3232.815992] Lustre: Mounted lustre-client [ 3245.169192] Lustre: lustre: nosquash_nids is cleared [ 3245.213787] Lustre: lustre: root_squash is set to 500:500 [ 3257.402574] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.203.2@tcp 192.168.0.[2,10]@tcp [ 3266.120430] Lustre: lustre: nosquash_nids set to 192.168.203.2/24@tcp [ 3268.941020] LustreError: 86509:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bd151f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3268.973542] LustreError: 86509:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3269.030499] Lustre: Unmounted lustre-client [ 3295.843497] Key type lgssc unregistered [ 3296.095074] LNet: 86911:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3296.110466] LNetError: 86911:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3296.130406] LNet: Removed LNI 192.168.203.2@tcp [ 3296.916663] Key type .llcrypt unregistered [ 3296.918612] Key type ._llcrypt unregistered [ 3310.119436] Key type ._llcrypt registered [ 3310.124633] Key type .llcrypt registered [ 3311.643422] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 02:33:30 (1761287610) [ 3314.069875] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3315.593525] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 02:33:34 (1761287614) [ 3317.973533] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3318.029589] alg: No test for adler32 (adler32-zlib) [ 3319.198486] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 3319.467465] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 3321.223203] Key type lgssc registered [ 3322.633331] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3366.790181] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3369.814038] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3386.841448] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3388.489671] Lustre: lustre: root_squash is set to 500:500 [ 3388.529672] Lustre: lustre: nosquash_nids set to 192.168.203.2/24@tcp [ 3394.114305] Lustre: Mounted lustre-client [ 3399.226089] LustreError: 90380:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bc3587800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3399.261068] LustreError: 90380:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3399.314932] Lustre: Unmounted lustre-client [ 3435.183517] Key type lgssc unregistered [ 3435.456173] LNet: 90783:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3435.473060] LNetError: 90783:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3435.514552] LNet: Removed LNI 192.168.203.2@tcp [ 3436.512703] Key type .llcrypt unregistered [ 3436.520195] Key type ._llcrypt unregistered [ 3452.774614] Key type ._llcrypt registered [ 3452.776254] Key type .llcrypt registered [ 3454.210851] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 3455.833541] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 02:35:54 (1761287754) [ 3471.949488] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3472.011105] alg: No test for adler32 (adler32-zlib) [ 3473.232947] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 3473.543756] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 3475.255129] Key type lgssc registered [ 3476.572357] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3548.458995] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3551.689693] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3570.354543] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3638.569085] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3642.491760] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3660.954642] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3669.904868] Lustre: Mounted lustre-client [ 3695.701626] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9c0bd0787000.ost_server_uuid 50 [ 3696.944575] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9c0bd0787000.ost_server_uuid in FULL state after 0 sec [ 3697.316521] Lustre: Mounted lustre-client [ 3699.609753] LustreError: 97682:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bd07a3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3699.636788] LustreError: 97682:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 3699.693975] Lustre: Unmounted lustre-client [ 3700.702533] LustreError: 97701:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bd0787000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3700.717487] LustreError: 97701:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 3700.731877] LustreError: 97701:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 3700.734034] LustreError: 97701:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 3700.778034] Lustre: Unmounted lustre-client [ 3736.602198] Key type lgssc unregistered [ 3736.850473] LNet: 98254:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3736.856266] LNetError: 98254:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3736.874317] LNet: Removed LNI 192.168.203.2@tcp [ 3737.413468] Key type .llcrypt unregistered [ 3737.415141] Key type ._llcrypt unregistered [ 3764.939300] Key type ._llcrypt registered [ 3764.943309] Key type .llcrypt registered [ 3765.194958] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3765.201776] alg: No test for adler32 (adler32-zlib) [ 3766.129548] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 3766.269485] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 3767.903176] Key type lgssc registered [ 3768.643443] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3821.116711] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3823.785844] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3837.576695] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3871.353658] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 02:42:50 (1761288170) [ 3929.349185] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3931.257068] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3942.856900] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3949.551788] Lustre: Mounted lustre-client [ 3969.887156] Lustre: 99037:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761288254/real 1761288254] req@0000000080d12553 x1846844395099648/t0(0) o400->lustre-OST0000-osc-ffff9c0bc5ad3800@192.168.203.102@tcp:28/4 lens 224/224 e 0 to 1 dl 1761288270 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3969.901267] Lustre: lustre-OST0000-osc-ffff9c0bc5ad3800: Connection to lustre-OST0000 (at 192.168.203.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3975.007211] Lustre: 99037:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761288259/real 1761288259] req@0000000001ff27fd x1846844395100160/t0(0) o400->lustre-OST0000-osc-ffff9c0bc5ad3800@192.168.203.102@tcp:28/4 lens 224/224 e 0 to 1 dl 1761288275 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3975.144135] Lustre: lustre-MDT0000-mdc-ffff9c0bc5ad3800: Connection to lustre-MDT0000 (at 192.168.203.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3981.279322] Lustre: 99040:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761288265/real 1761288265] req@000000007f9a3bc8 x1846844395100672/t0(0) o400->lustre-OST0000-osc-ffff9c0bc5ad3800@192.168.203.102@tcp:28/4 lens 224/224 e 0 to 1 dl 1761288281 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3987.423215] Lustre: 106172:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761288271/real 1761288271] req@0000000015403e34 x1846844395101568/t0(0) o101->MGC192.168.203.102@tcp@192.168.203.102@tcp:26/25 lens 328/344 e 0 to 1 dl 1761288287 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3987.437577] LustreError: MGC192.168.203.102@tcp: Connection to MGS (at 192.168.203.102@tcp) was lost; in progress operations using this service will fail [ 3987.444073] Lustre: 106172:0:(mgc_request.c:1910:mgc_process_log()) MGC192.168.203.102@tcp: IR log lustre-cliir failed, not fatal: rc = -5 [ 3987.454828] Lustre: Evicted from MGS (at 192.168.203.102@tcp) after server handle changed from 0x8560e48ef9ef94c4 to 0x8560e48ef9ef97fe [ 3987.468230] Lustre: MGC192.168.203.102@tcp: Connection restored to 192.168.203.102@tcp (at 192.168.203.102@tcp) [ 3991.722434] Lustre: lustre-MDT0000-mdc-ffff9c0bc5ad3800: Connection restored to 192.168.203.102@tcp (at 192.168.203.102@tcp) [ 3992.453795] LustreError: 106895:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bc5ad3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3992.468123] LustreError: 106895:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3992.513082] Lustre: Unmounted lustre-client [ 4013.877450] Key type lgssc unregistered [ 4014.055933] LNet: 107296:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4014.063740] LNetError: 107296:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4015.078909] LNet: Removed LNI 192.168.203.2@tcp [ 4015.508381] Key type .llcrypt unregistered [ 4015.511712] Key type ._llcrypt unregistered [ 4023.968191] Key type ._llcrypt registered [ 4023.970593] Key type .llcrypt registered [ 4024.895420] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 02:45:24 (1761288324) [ 4037.933191] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4037.979252] alg: No test for adler32 (adler32-zlib) [ 4038.911479] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 4039.039363] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 4040.663269] Key type lgssc registered [ 4041.308120] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4061.899699] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4147.917527] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4151.710878] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4170.207034] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4172.761048] Lustre: Mounted lustre-client [ 4174.553156] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 4175.925389] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 02:47:54 (1761288474) [ 4377.156859] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4387.442329] LustreError: 118058:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4387.499106] Lustre: Unmounted lustre-client [ 4486.759289] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4489.143608] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4501.834225] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4526.137750] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 02:53:45 (1761288825) [ 4566.018377] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4567.679479] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4577.542131] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4577.814730] Lustre: Mounted lustre-client [ 4579.409286] LustreError: 125463:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bc5a99800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4579.420885] LustreError: 125463:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4579.423540] LustreError: 125463:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4579.451935] Lustre: Unmounted lustre-client [ 4607.846761] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 02:55:07 (1761288907) [ 4649.284735] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4651.038602] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4660.404062] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4665.868826] Lustre: Mounted lustre-client [ 4667.496695] LustreError: 129505:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bc8acd000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4667.507944] LustreError: 129505:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4667.511018] LustreError: 129505:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4667.549514] Lustre: Unmounted lustre-client [ 4683.413766] Key type lgssc unregistered [ 4683.572627] LNet: 129907:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4683.575313] LNetError: 129907:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4683.584937] LNet: Removed LNI 192.168.203.2@tcp [ 4683.909317] Key type .llcrypt unregistered [ 4683.910932] Key type ._llcrypt unregistered [ 4691.199071] Key type ._llcrypt registered [ 4691.200588] Key type .llcrypt registered [ 4691.928779] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 02:56:31 (1761288991) [ 4693.155603] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4693.225652] alg: No test for adler32 (adler32-zlib) [ 4694.155748] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 4694.268940] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 4695.887156] Key type lgssc registered [ 4696.466370] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4718.936664] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4720.614710] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4728.036063] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4738.564507] Lustre: Mounted lustre-client [ 4749.541226] LustreError: 133002:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bd1d0f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4749.551777] LustreError: 133002:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4749.581136] Lustre: Unmounted lustre-client [ 4768.827188] Key type lgssc unregistered [ 4768.955684] LNet: 133404:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4768.960520] LNetError: 133404:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4768.970099] LNet: Removed LNI 192.168.203.2@tcp [ 4769.255355] Key type .llcrypt unregistered [ 4769.256810] Key type ._llcrypt unregistered [ 4775.722211] Key type ._llcrypt registered [ 4775.723685] Key type .llcrypt registered [ 4776.328989] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 02:57:55 (1761289075) [ 4777.186629] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4777.194546] alg: No test for adler32 (adler32-zlib) [ 4778.073181] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 4778.170074] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 4779.767190] Key type lgssc registered [ 4780.295617] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4805.166960] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4806.819412] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4814.707821] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4820.486820] Lustre: Mounted lustre-client [ 4825.575784] Lustre: lustre-OST0000-osc-ffff9c0bc9956800: Connection to lustre-OST0000 (at 192.168.203.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4831.449333] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff9c0bc9956800.ost_server_uuid 50 [ 4832.203131] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 4834.249478] LustreError: 136908:0:(osc_quota.c:199:osc_quotactl()) ptlrpc_queue_wait failed, rc: -11 [ 4841.753818] LustreError: 136932:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bc9956800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4841.760805] LustreError: 136932:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4841.787347] Lustre: Unmounted lustre-client [ 4855.098691] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 02:59:14 (1761289154) [ 4869.062797] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4870.800566] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4879.397883] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4889.450001] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4889.727109] Lustre: Mounted lustre-client [ 4895.203656] Lustre: lustre-OST0000-osc-ffff9c0bd1d0c000: Connection to lustre-OST0000 (at 192.168.203.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4903.095408] LustreError: 139555:0:(osc_quota.c:199:osc_quotactl()) ptlrpc_queue_wait failed, rc: -11 [ 4910.737983] LustreError: 139579:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bd1d0c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4910.749429] LustreError: 139579:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 4910.752983] LustreError: 139579:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4910.795619] Lustre: Unmounted lustre-client [ 4938.255736] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 03:00:37 (1761289237) [ 4950.397672] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4951.969084] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4960.110735] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4967.695241] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4968.085532] Lustre: Mounted lustre-client [ 4973.540117] Lustre: lustre-OST0000-osc-ffff9c0bc759b000: Connection to lustre-OST0000 (at 192.168.203.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4986.343178] LustreError: 142316:0:(osc_quota.c:199:osc_quotactl()) ptlrpc_queue_wait failed, rc: -11 [ 4993.989385] LustreError: 142340:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bc759b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4993.995330] LustreError: 142340:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 4994.006093] LustreError: 142340:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 4994.008832] LustreError: 142340:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 4994.047674] Lustre: Unmounted lustre-client [ 5021.772548] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 03:02:01 (1761289321) [ 5061.981352] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5063.586728] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5072.594858] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5113.722554] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5115.295898] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5122.718257] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5143.268764] Lustre: Mounted lustre-client [ 5172.319543] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5172.975996] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 5173.374394] LustreError: 148922:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bc8acc800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5173.380140] LustreError: 148922:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 5173.388511] LustreError: 148922:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5173.391766] LustreError: 148922:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 5173.414417] Lustre: Unmounted lustre-client [ 5194.112427] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 03:04:53 (1761289493) [ 5206.734641] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5208.300469] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5215.814085] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5233.175505] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5244.762488] Lustre: Mounted lustre-client [ 5273.044609] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5273.670245] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 5275.620632] Lustre: lustre-OST0001-osc-ffff9c0bc5a9d000: Connection to lustre-OST0001 (at 192.168.203.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5277.461223] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5287.720273] LustreError: 152493:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 5287.723445] LustreError: 152493:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5287.742991] Lustre: Unmounted lustre-client [ 5319.860559] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 03:06:59 (1761289619) [ 5331.823180] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5333.428187] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5341.141755] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5341.380276] Lustre: Mounted lustre-client [ 5350.414935] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5352.111704] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 1 sec [ 5356.845417] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9c0bc759a000.ost_server_uuid 50 [ 5357.466377] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9c0bc759a000.ost_server_uuid in FULL state after 0 sec [ 5358.287554] LustreError: 155916:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bc759a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5358.295782] LustreError: 155916:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 5358.298577] LustreError: 155916:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 5358.328169] Lustre: Unmounted lustre-client [ 5358.443952] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 5358.446729] Lustre: Skipped 3 previous similar messages [ 5358.497487] Lustre: Mounted lustre-client [ 5358.979841] LustreError: 155979:0:(sec.c:410:import_sec_validate_get()) import 00000000ead1c6ae (NEW) with no sec [ 5392.650521] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 03:08:12 (1761289692) [ 5404.990681] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5406.554908] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5414.091565] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5421.552788] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5421.722864] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 5427.190823] Lustre: Mounted lustre-client [ 5431.970991] LustreError: 158797:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bd0785000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5431.976023] LustreError: 158797:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 5431.988551] LustreError: 158797:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 5431.991040] LustreError: 158797:0:(obd_class.h:479:obd_check_dev()) Skipped 18 previous similar messages [ 5432.014127] Lustre: Unmounted lustre-client [ 5432.015458] Lustre: Skipped 1 previous similar message [ 5465.454949] Key type lgssc unregistered [ 5465.724741] LNet: 159245:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5465.728407] LNetError: 159245:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5465.754072] LNet: Removed LNI 192.168.203.2@tcp [ 5466.645299] Key type .llcrypt unregistered [ 5466.648599] Key type ._llcrypt unregistered [ 5482.601571] Key type ._llcrypt registered [ 5482.602836] Key type .llcrypt registered [ 5484.418807] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 03:09:42 (1761289782) [ 5486.403143] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5486.430293] alg: No test for adler32 (adler32-zlib) [ 5487.504503] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 5487.744531] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 5489.439187] Key type lgssc registered [ 5490.926897] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5525.022100] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5527.231843] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5537.829623] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5549.473496] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5549.851405] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 5550.964808] Lustre: Mounted lustre-client [ 5559.333409] LustreError: lustre-MDT0001-mdc-ffff9c0bc756b800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 5559.342547] Lustre: lustre-MDT0001-mdc-ffff9c0bc756b800: Connection restored to 192.168.203.102@tcp (at 192.168.203.102@tcp) [ 5561.454828] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5562.579151] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 5577.211355] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 5579.522927] LustreError: 163477:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bc756b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5579.541590] LustreError: 163477:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 5579.590747] Lustre: Unmounted lustre-client [ 5603.783656] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 03:11:43 (1761289903) [ 5661.995200] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5664.550907] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5678.941479] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5679.435703] Lustre: Mounted lustre-client [ 5696.565269] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5697.626286] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 5703.149516] Lustre: lustre-OST0001-osc-ffff9c0bd07a6000: Connection to lustre-OST0001 (at 192.168.203.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5705.806564] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5716.087157] LustreError: 168206:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 5716.093195] LustreError: 168206:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 5716.128596] Lustre: Unmounted lustre-client [ 5747.003124] Key type lgssc unregistered [ 5747.191642] LNet: 168613:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5747.200837] LNetError: 168613:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5747.213811] LNet: Removed LNI 192.168.203.2@tcp [ 5747.729542] Key type .llcrypt unregistered [ 5747.731462] Key type ._llcrypt unregistered [ 5776.448647] Key type ._llcrypt registered [ 5776.451621] Key type .llcrypt registered [ 5776.671638] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5776.679556] alg: No test for adler32 (adler32-zlib) [ 5777.608978] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 5777.764534] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 5779.383173] Key type lgssc registered [ 5780.058547] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5826.561248] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5828.352077] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5839.120760] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5867.258508] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 03:16:06 (1761290166) [ 5884.110449] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5885.853310] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5895.176634] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5901.810983] Lustre: Mounted lustre-client [ 5943.774115] LustreError: 174681:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bc5ad6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5943.800050] LustreError: 174681:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5943.869094] Lustre: Unmounted lustre-client [ 5966.548819] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5966.835108] Lustre: Mounted lustre-client [ 5999.952587] LustreError: 175338:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bc45a9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5999.965871] LustreError: 175338:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5999.968730] LustreError: 175338:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5999.997156] Lustre: Unmounted lustre-client [ 6018.245211] Key type lgssc unregistered [ 6018.383613] LNet: 175744:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6018.387110] LNetError: 175744:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6018.397826] LNet: Removed LNI 192.168.203.2@tcp [ 6018.689748] Key type .llcrypt unregistered [ 6018.691275] Key type ._llcrypt unregistered [ 6024.550377] Key type ._llcrypt registered [ 6024.551982] Key type .llcrypt registered [ 6025.082758] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 03:18:44 (1761290324) [ 6026.013699] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6026.021157] alg: No test for adler32 (adler32-zlib) [ 6026.911551] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 6027.012157] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 6028.607230] Key type lgssc registered [ 6029.034533] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6048.273860] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6049.708500] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6056.799202] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6067.184656] Lustre: Mounted lustre-client [ 6075.408541] LustreError: 178996:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bc43c6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6075.417138] LustreError: 178996:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6075.439219] Lustre: Unmounted lustre-client [ 6093.709167] Key type lgssc unregistered [ 6093.845255] LNet: 179399:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6093.851530] LNetError: 179399:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6093.862784] LNet: Removed LNI 192.168.203.2@tcp [ 6094.149607] Key type .llcrypt unregistered [ 6094.150984] Key type ._llcrypt unregistered [ 6099.132612] Key type ._llcrypt registered [ 6099.135719] Key type .llcrypt registered [ 6099.320795] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6099.348043] alg: No test for adler32 (adler32-zlib) [ 6100.267451] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 6100.381893] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 6101.999313] Key type lgssc registered [ 6102.567528] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6124.461602] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6126.161909] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6133.875859] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6144.517666] Lustre: Mounted lustre-client [ 6148.481750] LustreError: 181963:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bc802e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6148.497158] LustreError: 181963:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6148.524817] Lustre: Unmounted lustre-client [ 6167.638430] Key type lgssc unregistered [ 6167.780103] LNet: 182365:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6167.783578] LNetError: 182365:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6167.794891] LNet: Removed LNI 192.168.203.2@tcp [ 6168.087692] Key type .llcrypt unregistered [ 6168.089210] Key type ._llcrypt unregistered [ 6174.602104] Key type ._llcrypt registered [ 6174.603655] Key type .llcrypt registered [ 6175.204472] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 03:21:14 (1761290474) [ 6176.183475] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6176.195436] alg: No test for adler32 (adler32-zlib) [ 6177.077285] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 6177.176147] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 6178.767216] Key type lgssc registered [ 6179.317432] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6200.887757] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6202.456234] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6210.716405] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6217.223335] Lustre: Mounted lustre-client [ 6225.504981] LustreError: 185619:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0be0332000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6225.513677] LustreError: 185619:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6225.540248] Lustre: Unmounted lustre-client [ 6252.413548] Key type lgssc unregistered [ 6252.541688] LNet: 186023:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6252.544955] LNetError: 186023:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6252.552888] LNet: Removed LNI 192.168.203.2@tcp [ 6252.836365] Key type .llcrypt unregistered [ 6252.837784] Key type ._llcrypt unregistered [ 6257.543287] Key type ._llcrypt registered [ 6257.544763] Key type .llcrypt registered [ 6257.736069] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6257.742879] alg: No test for adler32 (adler32-zlib) [ 6258.635240] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 6258.734634] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 6260.335204] Key type lgssc registered [ 6260.839336] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6285.350261] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6287.165726] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6295.302214] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6301.662769] Lustre: Mounted lustre-client [ 6308.790409] LustreError: 188590:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bc9956800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6308.803923] LustreError: 188590:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6308.834287] Lustre: Unmounted lustre-client [ 6327.469825] Key type lgssc unregistered [ 6327.600902] LNet: 188991:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6327.604207] LNetError: 188991:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6327.614757] LNet: Removed LNI 192.168.203.2@tcp [ 6327.890318] Key type .llcrypt unregistered [ 6327.892564] Key type ._llcrypt unregistered [ 6333.933465] Key type ._llcrypt registered [ 6333.934524] Key type .llcrypt registered [ 6334.507804] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 03:23:54 (1761290634) [ 6343.243861] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6343.250811] alg: No test for adler32 (adler32-zlib) [ 6344.141875] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 6344.247279] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 6345.847569] Key type lgssc registered [ 6346.334130] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6382.916038] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6384.639903] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6394.713694] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6426.824093] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 03:25:26 (1761290726) [ 6439.479494] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6441.121621] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6449.230423] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6454.789418] Lustre: Mounted lustre-client [ 6457.224310] LustreError: 195494:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bc802f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6457.248355] LustreError: 195494:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6457.278959] Lustre: Unmounted lustre-client [ 6484.496384] Key type lgssc unregistered [ 6484.631713] LNet: 195898:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6484.635675] LNetError: 195898:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6484.644781] LNet: Removed LNI 192.168.203.2@tcp [ 6484.923414] Key type .llcrypt unregistered [ 6484.925071] Key type ._llcrypt unregistered [ 6491.284249] Key type ._llcrypt registered [ 6491.285843] Key type .llcrypt registered [ 6491.843940] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 6492.492111] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 03:26:32 (1761290792) [ 6499.577845] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6499.620428] alg: No test for adler32 (adler32-zlib) [ 6500.499649] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 6500.597386] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 6502.199174] Key type lgssc registered [ 6502.707427] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6541.491871] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6542.891592] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6551.364489] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid [ 6558.681326] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid [ 6560.967177] Lustre: Mounted lustre-client [ 6568.827529] LustreError: 201435:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bd0b4d800: lov tgt 1000 not cleaned! deathrow=0, lovrc=1 [ 6568.837160] LustreError: 201435:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6568.867673] Lustre: Unmounted lustre-client [ 6644.436373] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6648.145194] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6668.185460] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6720.019697] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 03:30:18 (1761291018) [ 6721.728089] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 6723.971171] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 03:30:22 (1761291022) [ 6773.406451] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6777.749995] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6811.763579] Key type lgssc unregistered [ 6811.948107] LNet: 207609:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6811.953290] LNetError: 207609:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6812.974895] LNet: Removed LNI 192.168.203.2@tcp [ 6813.505564] Key type .llcrypt unregistered [ 6813.510295] Key type ._llcrypt unregistered [ 6824.674937] Key type ._llcrypt registered [ 6824.676237] Key type .llcrypt registered [ 6825.747727] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 03:32:04 (1761291124) [ 6850.547714] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6850.577753] alg: No test for adler32 (adler32-zlib) [ 6851.619174] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 6851.789493] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 6853.464450] Key type lgssc registered [ 6854.629819] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6888.983093] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6892.386969] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6912.476609] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6938.998355] Key type lgssc unregistered [ 6939.358819] LNet: 211245:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6939.367951] LNetError: 211245:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6939.404964] LNet: Removed LNI 192.168.203.2@tcp [ 6940.255864] Key type .llcrypt unregistered [ 6940.266251] Key type ._llcrypt unregistered [ 6955.449337] Key type ._llcrypt registered [ 6955.450997] Key type .llcrypt registered [ 6956.526430] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 03:34:15 (1761291255) [ 6958.863075] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6958.904228] alg: No test for adler32 (adler32-zlib) [ 6959.989119] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 6960.261725] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 6961.951286] Key type lgssc registered [ 6963.229486] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7005.853374] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7008.863916] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7024.465589] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7035.450993] Lustre: Mounted lustre-client [ 7045.612736] Lustre: lustre-MDT0000-mdc-ffff9c0bc802e800: Connection to lustre-MDT0000 (at 192.168.203.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7055.856896] Lustre: lustre-MDT0001-mdc-ffff9c0bc802e800: Connection to lustre-MDT0001 (at 192.168.203.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7066.079144] Lustre: 212234:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761291350/real 1761291350] req@00000000db2c5973 x1846847744328320/t0(0) o400->MGC192.168.203.102@tcp@192.168.203.102@tcp:26/25 lens 224/224 e 0 to 1 dl 1761291366 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 7066.100208] LustreError: MGC192.168.203.102@tcp: Connection to MGS (at 192.168.203.102@tcp) was lost; in progress operations using this service will fail [ 7076.395720] Lustre: Evicted from MGS (at 192.168.203.102@tcp) after server handle changed from 0xe4644d3e5f6442a6 to 0xe4644d3e5f645f94 [ 7076.416275] Lustre: MGC192.168.203.102@tcp: Connection restored to 192.168.203.102@tcp (at 192.168.203.102@tcp) [ 7087.144556] LustreError: lustre-MDT0000-mdc-ffff9c0bc802e800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 7087.215024] Lustre: lustre-MDT0000-mdc-ffff9c0bc802e800: Connection restored to 192.168.203.102@tcp (at 192.168.203.102@tcp) [ 7093.578438] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7095.372541] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7099.005835] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7100.747670] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7102.845881] LustreError: 215663:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bc802e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7102.856794] LustreError: 215663:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7102.905804] Lustre: Unmounted lustre-client [ 7128.912308] Key type lgssc unregistered [ 7129.309473] LNet: 216066:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7129.313866] LNetError: 216066:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7129.332152] LNet: Removed LNI 192.168.203.2@tcp [ 7130.237083] Key type .llcrypt unregistered [ 7130.242457] Key type ._llcrypt unregistered [ 7147.962777] Key type ._llcrypt registered [ 7147.964297] Key type .llcrypt registered [ 7149.303600] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 03:37:28 (1761291448) [ 7152.421552] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7152.457275] alg: No test for adler32 (adler32-zlib) [ 7153.691233] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 7154.044151] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 7155.736703] Key type lgssc registered [ 7157.078365] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7221.234286] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7225.584531] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7271.965624] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7274.719141] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7298.412552] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7313.496668] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 7347.801819] Key type lgssc unregistered [ 7347.994709] LNet: 221184:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7348.007202] LNetError: 221184:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7348.027770] LNet: Removed LNI 192.168.203.2@tcp [ 7348.536438] Key type .llcrypt unregistered [ 7348.537803] Key type ._llcrypt unregistered [ 7384.002397] Key type ._llcrypt registered [ 7384.004276] Key type .llcrypt registered [ 7384.336351] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7384.347896] alg: No test for adler32 (adler32-zlib) [ 7385.391118] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 7385.647140] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 7387.327175] Key type lgssc registered [ 7388.333290] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7464.570749] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7467.863501] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7487.223976] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7526.857729] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 03:43:45 (1761291825) [ 7623.294423] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7626.958746] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7646.846891] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7685.345445] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 03:46:24 (1761291984) [ 7692.186911] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 03:46:30 (1761291990) [ 7721.942630] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7725.387347] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7741.691763] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7748.647868] Lustre: Mounted lustre-client [ 7754.497724] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [ 7757.245249] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 7759.486079] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 7761.505360] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [ 7763.947844] Lustre: lustre-MDT0000-mdc-ffff9c0bc87d2000: Connection to lustre-MDT0000 (at 192.168.203.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7780.319966] Lustre: 221971:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761292064/real 1761292064] req@000000004e547346 x1846848189900800/t0(0) o400->MGC192.168.203.102@tcp@192.168.203.102@tcp:26/25 lens 224/224 e 0 to 1 dl 1761292080 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 7780.371652] LustreError: MGC192.168.203.102@tcp: Connection to MGS (at 192.168.203.102@tcp) was lost; in progress operations using this service will fail [ 7788.216340] Lustre: lustre-MDT0000-mdc-ffff9c0bc87d2000: Connection restored to 192.168.203.102@tcp (at 192.168.203.102@tcp) [ 7790.637486] Lustre: Evicted from MGS (at 192.168.203.102@tcp) after server handle changed from 0xacdf90b95a1b759 to 0xacdf90b95a1babd [ 7790.656202] Lustre: MGC192.168.203.102@tcp: Connection restored to 192.168.203.102@tcp (at 192.168.203.102@tcp) [ 7793.521768] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7795.200124] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7798.470115] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [ 7800.811902] Lustre: lustre-MDT0000-mdc-ffff9c0bc87d2000: Connection to lustre-MDT0000 (at 192.168.203.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7816.098650] Lustre: 221971:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761292100/real 1761292100] req@00000000e6ad3a5e x1846848189906944/t0(0) o400->MGC192.168.203.102@tcp@192.168.203.102@tcp:26/25 lens 224/224 e 0 to 1 dl 1761292116 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 7816.126058] LustreError: MGC192.168.203.102@tcp: Connection to MGS (at 192.168.203.102@tcp) was lost; in progress operations using this service will fail [ 7816.142736] Lustre: 232365:0:(mgc_request.c:1910:mgc_process_log()) MGC192.168.203.102@tcp: IR log lustre-cliir failed, not fatal: rc = -5 [ 7824.553529] Lustre: lustre-MDT0000-mdc-ffff9c0bc87d2000: Connection restored to 192.168.203.102@tcp (at 192.168.203.102@tcp) [ 7826.477043] Lustre: Evicted from MGS (at 192.168.203.102@tcp) after server handle changed from 0xacdf90b95a1babd to 0xacdf90b95a1bd9c [ 7829.444658] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7830.856493] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7834.183473] LustreError: 234809:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bc87d2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7834.215320] LustreError: 234809:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7834.302250] Lustre: Unmounted lustre-client [ 7901.048345] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7904.474275] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7922.212696] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7928.386963] Lustre: Mounted lustre-client [ 7929.956752] LustreError: 236436:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bc87d2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7929.971561] LustreError: 236436:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7929.975312] LustreError: 236436:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 7930.048258] Lustre: Unmounted lustre-client [ 7957.083429] Key type lgssc unregistered [ 7957.420050] LNet: 236836:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7957.428697] LNetError: 236836:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7957.451182] LNet: Removed LNI 192.168.203.2@tcp [ 7958.278509] Key type .llcrypt unregistered [ 7958.280508] Key type ._llcrypt unregistered [ 7974.318163] Key type ._llcrypt registered [ 7974.319810] Key type .llcrypt registered [ 7975.632686] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 03:51:14 (1761292274) [ 7979.208753] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7979.377340] alg: No test for adler32 (adler32-zlib) [ 7980.454816] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 7980.670200] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 7982.367221] Key type lgssc registered [ 7983.458129] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8027.234200] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8031.453583] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8048.523932] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8051.207400] Lustre: Mounted lustre-client [ 8055.995776] LustreError: 240009:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bc6d1b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8056.019552] LustreError: 240009:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8056.090172] Lustre: Unmounted lustre-client [ 8115.809255] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8119.187766] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8140.356877] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8141.160271] Lustre: Mounted lustre-client [ 8150.875905] LustreError: 241610:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bc3584000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8150.896776] LustreError: 241610:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8150.899072] LustreError: 241610:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 8150.993488] Lustre: Unmounted lustre-client [ 8219.420593] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8223.083188] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8242.060297] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8242.704836] Lustre: Mounted lustre-client [ 8244.103634] LustreError: 243227:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bc467c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8244.130494] LustreError: 243227:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8244.133883] LustreError: 243227:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 8244.221602] Lustre: Unmounted lustre-client [ 8268.264666] Key type lgssc unregistered [ 8268.562909] LNet: 243629:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8268.567556] LNetError: 243629:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8269.618207] LNet: Removed LNI 192.168.203.2@tcp [ 8270.264135] Key type .llcrypt unregistered [ 8270.265585] Key type ._llcrypt unregistered [ 8286.341887] Key type ._llcrypt registered [ 8286.345355] Key type .llcrypt registered [ 8287.938585] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 03:56:26 (1761292586) [ 8291.655555] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8291.691168] alg: No test for adler32 (adler32-zlib) [ 8292.925358] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 8293.254151] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 8294.967239] Key type lgssc registered [ 8296.720476] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8342.245615] Key type lgssc unregistered [ 8342.499312] LNet: 245943:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8342.515293] LNetError: 245943:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8342.537657] LNet: Removed LNI 192.168.203.2@tcp [ 8343.160109] Key type .llcrypt unregistered [ 8343.161828] Key type ._llcrypt unregistered [ 8371.474691] Key type ._llcrypt registered [ 8371.476422] Key type .llcrypt registered [ 8371.939995] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8371.961385] alg: No test for adler32 (adler32-zlib) [ 8373.018645] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 8373.242293] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 8374.935293] Key type lgssc registered [ 8375.948653] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8453.530491] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8457.078288] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8476.108216] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8516.557723] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [ 8518.296861] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 04:00:17 (1761292817) [ 8547.953920] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8551.270720] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8570.136285] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8590.917929] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 8592.799412] Lustre: Mounted lustre-client [ 8597.999650] Lustre: lustre-OST0001-osc-ffff9c0bc5ad7800: Connection to lustre-OST0001 (at 192.168.203.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8603.652521] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 8613.930319] LustreError: 252459:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 8613.972188] Lustre: Unmounted lustre-client [ 8662.854229] Key type lgssc unregistered [ 8663.146651] LNet: 252866:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8663.151939] LNetError: 252866:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8663.189378] LNet: Removed LNI 192.168.203.2@tcp [ 8663.743413] Key type .llcrypt unregistered [ 8663.745426] Key type ._llcrypt unregistered [ 8702.606433] Key type ._llcrypt registered [ 8702.618822] Key type .llcrypt registered [ 8703.143495] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8703.182459] alg: No test for adler32 (adler32-zlib) [ 8704.277751] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 8704.492828] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 8706.183176] Key type lgssc registered [ 8707.371482] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8777.689574] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8781.056732] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8798.801782] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8838.233159] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 04:05:37 (1761293137) [ 8874.212062] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8877.040888] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8897.330547] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 04:06:36 (1761293196) [ 8925.346464] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8929.044473] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8946.109352] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8951.866117] Lustre: Mounted lustre-client [ 8961.028760] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 8968.588920] LustreError: 260708:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8968.639733] Lustre: Unmounted lustre-client [ 9060.272366] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9063.732707] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9104.727712] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9105.459061] Lustre: Mounted lustre-client [ 9106.821416] LustreError: 262739:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bc6d1c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9106.840322] LustreError: 262739:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9106.843544] LustreError: 262739:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 9106.898223] Lustre: Unmounted lustre-client [ 9130.984888] Key type lgssc unregistered [ 9131.227681] LNet: 263142:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9131.233737] LNetError: 263142:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9131.252443] LNet: Removed LNI 192.168.203.2@tcp [ 9131.756224] Key type .llcrypt unregistered [ 9131.757747] Key type ._llcrypt unregistered [ 9153.980680] Key type ._llcrypt registered [ 9153.986843] Key type .llcrypt registered [ 9154.354134] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9154.384054] alg: No test for adler32 (adler32-zlib) [ 9155.373989] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 9155.559627] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 9157.247201] Key type lgssc registered [ 9158.144515] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9215.899763] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9218.388742] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9232.710058] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9282.425964] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [ 9283.942913] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 04:13:02 (1761293582) [ 9307.467588] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9310.328334] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9324.026401] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9336.866783] Lustre: Mounted lustre-client [ 9339.009025] LustreError: 269199:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bc87d6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9339.030760] LustreError: 269199:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9339.084977] Lustre: Unmounted lustre-client [ 9370.674507] Key type lgssc unregistered [ 9370.876376] LNet: 269603:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9370.880316] LNetError: 269603:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9370.889278] LNet: Removed LNI 192.168.203.2@tcp [ 9371.327183] Key type .llcrypt unregistered [ 9371.328573] Key type ._llcrypt unregistered [ 9382.752863] Key type ._llcrypt registered [ 9382.754261] Key type .llcrypt registered [ 9383.651547] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [ 9384.847429] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 04:14:44 (1761293684) [ 9391.526321] Key type .llcrypt unregistered [ 9391.527867] Key type ._llcrypt unregistered [ 9397.095202] Key type ._llcrypt registered [ 9397.099525] Key type .llcrypt registered [ 9397.356307] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9397.376554] alg: No test for adler32 (adler32-zlib) [ 9398.322481] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 9398.485561] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 9400.138833] Key type lgssc registered [ 9400.821301] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9431.428421] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9445.410155] Lustre: Mounted lustre-client [ 9446.859711] LustreError: 272619:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bc467a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9446.872757] LustreError: 272619:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9446.911202] Lustre: Unmounted lustre-client [ 9464.893812] Key type lgssc unregistered [ 9465.070087] LNet: 273021:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9465.075238] LNetError: 273021:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9465.091689] LNet: Removed LNI 192.168.203.2@tcp [ 9465.520483] Key type .llcrypt unregistered [ 9465.521723] Key type ._llcrypt unregistered [ 9475.834425] Key type ._llcrypt registered [ 9475.835523] Key type .llcrypt registered [ 9476.673768] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 04:16:15 (1761293775) [ 9477.933480] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9477.954677] alg: No test for adler32 (adler32-zlib) [ 9478.917474] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 9479.034444] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 9480.663317] Key type lgssc registered [ 9481.357398] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9550.003398] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9568.293054] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9570.626527] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9573.050779] Lustre: Mounted lustre-client [ 9574.351694] LustreError: 276065:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bd0a80000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9574.372906] LustreError: 276065:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9574.407310] Lustre: Unmounted lustre-client [ 9597.500377] Key type lgssc unregistered [ 9597.689224] LNet: 276469:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9597.694746] LNetError: 276469:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9597.712247] LNet: Removed LNI 192.168.203.2@tcp [ 9598.121118] Key type .llcrypt unregistered [ 9598.124348] Key type ._llcrypt unregistered [ 9608.800846] Key type ._llcrypt registered [ 9608.802361] Key type .llcrypt registered [ 9609.780199] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 04:18:28 (1761293908) [ 9611.325898] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9611.339778] alg: No test for adler32 (adler32-zlib) [ 9612.373736] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 9612.545400] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 9614.191143] Key type lgssc registered [ 9615.178954] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9646.165862] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9648.603630] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9659.961505] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9661.338545] Lustre: Mounted lustre-client [ 9666.543657] Lustre: lustre-MDT0000-mdc-ffff9c0bc802e000: Connection to lustre-MDT0000 (at 192.168.203.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9670.032629] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 9670.051176] LustreError: 279534:0:(file.c:6108:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [ 9670.239522] LustreError: 279553:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bc802e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9670.246877] LustreError: MGC192.168.203.102@tcp: Connection to MGS (at 192.168.203.102@tcp) was lost; in progress operations using this service will fail [ 9670.255278] LustreError: 279553:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9670.269868] Lustre: Unmounted lustre-client [ 9682.158175] Key type lgssc unregistered [ 9682.341383] LNet: 279946:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9682.345811] LNetError: 279946:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9682.361634] LNet: Removed LNI 192.168.203.2@tcp [ 9682.868763] Key type .llcrypt unregistered [ 9682.870318] Key type ._llcrypt unregistered [ 9691.077174] Key type ._llcrypt registered [ 9691.079087] Key type .llcrypt registered [ 9691.817107] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 04:19:51 (1761293991) [ 9693.147440] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9693.158204] alg: No test for adler32 (adler32-zlib) [ 9694.107027] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 9694.227733] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 9695.850196] Key type lgssc registered [ 9696.730878] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9723.832857] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9725.797767] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9736.292803] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9742.845064] Lustre: Mounted lustre-client [ 9751.120931] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 9751.825365] LustreError: 283037:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bc843e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9751.835642] LustreError: 283037:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9751.864504] Lustre: Unmounted lustre-client [ 9765.440675] Key type lgssc unregistered [ 9765.591210] LNet: 283430:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9765.596742] LNetError: 283430:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9765.607276] LNet: Removed LNI 192.168.203.2@tcp [ 9765.992175] Key type .llcrypt unregistered [ 9765.993516] Key type ._llcrypt unregistered [ 9773.810743] Key type ._llcrypt registered [ 9773.812313] Key type .llcrypt registered [ 9774.578970] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 04:21:13 (1761294073) [ 9784.188537] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9784.199678] alg: No test for adler32 (adler32-zlib) [ 9785.101705] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 9785.231275] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 9786.848136] Key type lgssc registered [ 9787.460230] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9844.515562] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9846.930821] Lustre: Mounted lustre-client [ 9872.361397] Lustre: lustre-MDT0001-mdc-ffff9c0bc8028800: Connection to lustre-MDT0001 (at 192.168.203.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9877.261420] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 9887.536066] LustreError: 288771:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 9887.574340] Lustre: Unmounted lustre-client [ 9916.633993] Key type lgssc unregistered [ 9916.896200] LNet: 289172:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9916.899880] LNetError: 289172:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9916.914797] LNet: Removed LNI 192.168.203.2@tcp [ 9917.295231] Key type .llcrypt unregistered [ 9917.296404] Key type ._llcrypt unregistered [ 9926.520238] Key type ._llcrypt registered [ 9926.521556] Key type .llcrypt registered [ 9927.457833] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 04:23:46 (1761294226) [ 9928.575590] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [ 9929.609191] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 04:23:48 (1761294228) [ 9930.579536] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [ 9931.443300] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 04:23:50 (1761294230) [ 9932.324488] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [ 9933.122879] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 04:23:52 (1761294232) [ 9933.867259] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [ 9934.702300] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 04:23:54 (1761294234) [ 9935.499288] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [ 9936.306956] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 04:23:55 (1761294235) [ 9940.637050] Key type .llcrypt unregistered [ 9940.638547] Key type ._llcrypt unregistered [ 9944.362574] Key type ._llcrypt registered [ 9944.365921] Key type .llcrypt registered [ 9944.627649] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9944.637929] alg: No test for adler32 (adler32-zlib) [ 9945.587732] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [ 9945.754910] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [ 9947.391416] Key type lgssc registered [ 9948.118819] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9990.765855] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9992.804417] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10003.420786] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10004.695272] Lustre: Mounted lustre-client [10005.560437] LustreError: 294756:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bd0bf0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10005.570271] LustreError: 294756:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [10005.608777] Lustre: Unmounted lustre-client [10033.823368] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 04:25:33 (1761294333) [10050.972445] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10053.061955] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10063.779024] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10064.252915] Lustre: Mounted lustre-client [10065.069725] LustreError: 296869:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bd1578000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10065.085031] LustreError: 296869:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [10065.087753] LustreError: 296869:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [10065.125714] Lustre: Unmounted lustre-client [10095.830294] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 04:26:35 (1761294395) [10138.113745] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10140.229860] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10151.218640] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10191.662692] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 04:28:11 (1761294491) [10196.360199] Key type lgssc unregistered [10196.484861] LNet: 302313:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10196.487842] LNetError: 302313:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10196.496862] LNet: Removed LNI 192.168.203.2@tcp [10196.774793] Key type .llcrypt unregistered [10196.776298] Key type ._llcrypt unregistered [10324.325620] Key type ._llcrypt registered [10324.327214] Key type .llcrypt registered [10324.517252] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10324.523736] alg: No test for adler32 (adler32-zlib) [10325.408211] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [10325.517174] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [10327.119187] Key type lgssc registered [10327.656873] Lustre: Echo OBD driver; http://www.lustre.org/ [10337.584426] Lustre: DEBUG MARKER: SKIP: conf-sanity test_73c unable to configure net [10343.038189] Key type lgssc unregistered [10343.178709] LNet: 303994:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10343.184144] LNetError: 303994:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10343.195866] LNet: Removed LNI 192.168.203.2@tcp [10343.506632] Key type .llcrypt unregistered [10343.509186] Key type ._llcrypt unregistered [10348.528462] Key type ._llcrypt registered [10348.530097] Key type .llcrypt registered [10349.122500] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 04:30:48 (1761294648) [10359.217038] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10359.255810] alg: No test for adler32 (adler32-zlib) [10360.149888] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [10360.262828] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [10361.871194] Key type lgssc registered [10362.442469] Lustre: Echo OBD driver; http://www.lustre.org/ [10388.497116] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 04:31:27 (1761294687) [10395.733149] Key type lgssc unregistered [10395.923832] LNet: 307538:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10395.927526] LNetError: 307538:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10395.943082] LNet: Removed LNI 192.168.203.2@tcp [10396.290955] Key type .llcrypt unregistered [10396.293884] Key type ._llcrypt unregistered [10402.456634] Key type ._llcrypt registered [10402.458246] Key type .llcrypt registered [10402.672930] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10402.690211] alg: No test for adler32 (adler32-zlib) [10403.649470] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [10403.801502] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [10405.431222] Key type lgssc registered [10406.176441] Lustre: Echo OBD driver; http://www.lustre.org/ [10435.881625] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10437.556206] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10448.455652] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10453.783759] LNet: Added LNI 192.168.203.2@tcp42 [8/256/0/180] [10454.920073] LNet: Removed LNI 192.168.203.2@tcp [10456.447217] Lustre: 310434:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff9c0bd0780000: config has no valid NIDs, force dynamic NIDs [10457.581751] Lustre: Mounted lustre-client [10457.623430] LustreError: 310449:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bd0780000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10457.635834] LustreError: 310449:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [10457.680966] Lustre: Unmounted lustre-client [10487.623133] Key type lgssc unregistered [10487.794216] LNet: 311287:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10487.797836] LNetError: 311287:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10487.806507] LNet: Removed LNI 192.168.203.2@tcp42 [10488.069451] Key type .llcrypt unregistered [10488.071062] Key type ._llcrypt unregistered [10493.299406] Key type ._llcrypt registered [10493.300797] Key type .llcrypt registered [10494.001616] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 04:33:13 (1761294793) [10495.166928] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10495.176608] alg: No test for adler32 (adler32-zlib) [10496.095708] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [10496.212298] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [10497.823175] Key type lgssc registered [10498.484211] Lustre: Echo OBD driver; http://www.lustre.org/ [10521.248381] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10522.944222] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10531.675520] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10538.487658] Lustre: Mounted lustre-client [10570.552117] Lustre: Mounted fs15246-client [10577.849688] LustreError: 315417:0:(lov_obd.c:783:lov_cleanup()) fs15246-clilov-ffff9c0be0337000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10577.860564] LustreError: 315417:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [10577.885193] Lustre: Unmounted fs15246-client [10595.443654] LustreError: 315635:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0bc8703800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10595.459436] LustreError: 315635:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [10595.461625] LustreError: 315635:0:(obd_class.h:479:obd_check_dev()) Skipped 3 previous similar messages [10595.498333] Lustre: Unmounted lustre-client [10623.662769] Key type lgssc unregistered [10623.822463] LNet: 316037:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10623.825969] LNetError: 316037:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10623.837947] LNet: Removed LNI 192.168.203.2@tcp [10624.140350] Key type .llcrypt unregistered [10624.142692] Key type ._llcrypt unregistered [10628.682842] Key type ._llcrypt registered [10628.684261] Key type .llcrypt registered [10629.270737] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 04:35:28 (1761294928) [10630.102723] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10630.124320] alg: No test for adler32 (adler32-zlib) [10631.018636] Lustre: Lustre: Build Version: 2.16.59_49_g4265256 [10631.124815] LNet: Added LNI 192.168.203.2@tcp [8/256/0/180] [10632.727221] Key type lgssc registered [10633.248824] Lustre: Echo OBD driver; http://www.lustre.org/ [10691.515762] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10693.016880] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10702.265478] Lustre: DEBUG MARKER: oleg302-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10755.983040] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 10611 sec ========= 04:37:35 (1761295055) [10756.666852] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 04:37:36 (1761295056) === [10758.025981] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 04:37:37 (1761295057) === [10770.295460] Key type lgssc unregistered [10770.428473] LNet: 325180:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10770.431690] LNetError: 325180:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10770.441547] LNet: Removed LNI 192.168.203.2@tcp [10770.704649] Key type .llcrypt unregistered [10770.705954] Key type ._llcrypt unregistered