[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.17.0-10.fc44 06/10/2025 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 464484928 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.988 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 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 2528MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001011] APIC: Switch to symmetric I/O mode setup [ 0.002355] x2apic enabled [ 0.003007] Switched APIC routing to physical x2apic. [ 0.004000] kvm-guest: setup PV IPIs [ 0.006000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.006000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x22982c12b6d, max_idle_ns: 440795281273 ns [ 0.006019] Calibrating delay loop (skipped) preset value.. 4799.97 BogoMIPS (lpj=2399988) [ 0.007011] pid_max: default: 32768 minimum: 301 [ 0.008137] LSM: Security Framework initializing [ 0.009040] Yama: becoming mindful. [ 0.010025] SELinux: Initializing. [ 0.011052] *** VALIDATE selinux *** [ 0.018734] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.022621] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.023166] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.024094] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.026075] *** VALIDATE tmpfs *** [ 0.027417] *** VALIDATE proc *** [ 0.028209] *** VALIDATE cgroup *** [ 0.029007] *** VALIDATE cgroup2 *** [ 0.031196] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.032139] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.033000] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.033026] Spectre V2 : User space: Vulnerable [ 0.034007] Speculative Store Bypass: Vulnerable [ 0.037578] debug: unmapping init [mem 0xffffffff8ce59000-0xffffffff8ce60fff] [ 0.040000] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.040659] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.041024] ... version: 2 [ 0.042010] ... bit width: 48 [ 0.043007] ... generic registers: 4 [ 0.044012] ... value mask: 0000ffffffffffff [ 0.045019] ... max period: 00007fffffffffff [ 0.046017] ... fixed-purpose events: 3 [ 0.047013] ... event mask: 000000070000000f [ 0.048429] rcu: Hierarchical SRCU implementation. [ 0.050909] smp: Bringing up secondary CPUs ... [ 0.051770] x86: Booting SMP configuration: [ 0.052030] .... node #0, CPUs: #1 #2 #3 [ 0.059080] smp: Brought up 1 node, 4 CPUs [ 0.061025] smpboot: Max logical packages: 1 [ 0.062013] smpboot: Total of 4 processors activated (19199.90 BogoMIPS) [ 0.147031] node 0 deferred pages initialised in 82ms [ 0.151012] devtmpfs: initialized [ 0.152295] x86/mm: Memory block size: 128MB [ 0.155000] gcov: version magic: 0x41383552 [ 0.159384] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.162106] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.165395] pinctrl core: initialized pinctrl subsystem [ 0.167207] [ 0.167806] ************************************************************* [ 0.170016] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.172013] ** ** [ 0.174016] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.176016] ** ** [ 0.178016] ** This means that this kernel is built to expose internal ** [ 0.181015] ** IOMMU data structures, which may compromise security on ** [ 0.183013] ** your system. ** [ 0.185015] ** ** [ 0.188016] ** If you see this message and you are not debugging the ** [ 0.191016] ** kernel, report this immediately to your vendor! ** [ 0.192012] ** ** [ 0.195015] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.197010] ************************************************************* [ 0.199865] NET: Registered protocol family 16 [ 0.201560] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.204091] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.205065] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.208011] cpuidle: using governor menu [ 0.209843] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.212636] PCI: Using configuration type 1 for base access [ 0.214174] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.225088] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.227022] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.231105] cryptd: max_cpu_qlen set to 1000 [ 0.234009] ACPI: Added _OSI(Module Device) [ 0.236017] ACPI: Added _OSI(Processor Device) [ 0.238012] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.240016] ACPI: Added _OSI(Processor Aggregator Device) [ 0.245864] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.254149] ACPI: Interpreter enabled [ 0.256131] ACPI: PM: (supports S0 S3 S4 S5) [ 0.258014] ACPI: Using IOAPIC for interrupt routing [ 0.260155] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.264802] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.277731] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.280158] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.284027] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.288148] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.293842] acpiphp: Slot [2] registered [ 0.296164] acpiphp: Slot [5] registered [ 0.297168] acpiphp: Slot [6] registered [ 0.299194] acpiphp: Slot [3] registered [ 0.301192] acpiphp: Slot [4] registered [ 0.302176] acpiphp: Slot [7] registered [ 0.304104] acpiphp: Slot [8] registered [ 0.305110] acpiphp: Slot [9] registered [ 0.307119] acpiphp: Slot [10] registered [ 0.309134] acpiphp: Slot [11] registered [ 0.311088] acpiphp: Slot [12] registered [ 0.312230] acpiphp: Slot [13] registered [ 0.314126] acpiphp: Slot [14] registered [ 0.315187] acpiphp: Slot [15] registered [ 0.317099] acpiphp: Slot [16] registered [ 0.318221] acpiphp: Slot [17] registered [ 0.320148] acpiphp: Slot [18] registered [ 0.322216] acpiphp: Slot [19] registered [ 0.324146] acpiphp: Slot [20] registered [ 0.326140] acpiphp: Slot [21] registered [ 0.328140] acpiphp: Slot [22] registered [ 0.330134] acpiphp: Slot [23] registered [ 0.331133] acpiphp: Slot [24] registered [ 0.333169] acpiphp: Slot [25] registered [ 0.335090] acpiphp: Slot [26] registered [ 0.336114] acpiphp: Slot [27] registered [ 0.338258] acpiphp: Slot [28] registered [ 0.340214] acpiphp: Slot [29] registered [ 0.341139] acpiphp: Slot [30] registered [ 0.343144] acpiphp: Slot [31] registered [ 0.344085] PCI host bridge to bus 0000:00 [ 0.346027] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.348043] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.351030] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.354042] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.356039] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.359043] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.361225] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.365407] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.368547] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.375000] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.379051] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.383020] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.385017] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.387015] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.389714] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.393826] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.396046] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.398962] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.403011] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.413021] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.419013] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.425877] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.448079] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.463021] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.478023] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.487861] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.511020] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.518990] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.539023] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.550213] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.553455] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.556439] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.558428] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.561306] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.566751] iommu: Default domain type: Passthrough [ 0.568503] SCSI subsystem initialized [ 0.570140] ACPI: bus type USB registered [ 0.572169] usbcore: registered new interface driver usbfs [ 0.574160] usbcore: registered new interface driver hub [ 0.576133] usbcore: registered new device driver usb [ 0.578188] pps_core: LinuxPPS API ver. 1 registered [ 0.581013] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.584087] PTP clock support registered [ 0.586117] EDAC MC: Ver: 3.0.0 [ 0.588167] PCI: Using ACPI for IRQ routing [ 0.589949] NetLabel: Initializing [ 0.591012] NetLabel: domain hash size = 128 [ 0.593013] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.595109] NetLabel: unlabeled traffic allowed by default [ 0.598046] vgaarb: loaded [ 0.599280] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.600018] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.607584] clocksource: Switched to clocksource kvm-clock [ 0.738117] VFS: Disk quotas dquot_6.6.0 [ 0.739879] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.742632] *** VALIDATE ramfs *** [ 0.743881] *** VALIDATE hugetlbfs *** [ 0.745699] pnp: PnP ACPI init [ 0.748169] pnp: PnP ACPI: found 6 devices [ 0.780061] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.783785] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.785982] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.788165] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.790651] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.792747] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.795634] NET: Registered protocol family 2 [ 0.797847] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.802883] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.806108] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.811295] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.814773] TCP: Hash tables configured (established 65536 bind 65536) [ 0.817619] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.820609] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.823338] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.826381] NET: Registered protocol family 1 [ 0.828979] RPC: Registered named UNIX socket transport module. [ 0.831247] RPC: Registered udp transport module. [ 0.832906] RPC: Registered tcp transport module. [ 0.834603] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.836786] NET: Registered protocol family 44 [ 0.838289] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.840593] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.842620] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.844709] PCI: CLS 0 bytes, default 64 [ 0.846280] Unpacking initramfs... [ 2.359721] debug: unmapping init [mem 0xffff8dbfbcc64000-0xffff8dbfbffcffff] [ 2.363879] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.365921] software IO TLB: mapped [mem 0x00000000b8c64000-0x00000000bcc64000] (64MB) [ 2.368865] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x22982c12b6d, max_idle_ns: 440795281273 ns [ 2.877565] Initialise system trusted keyrings [ 2.879149] Key type blacklist registered [ 2.880949] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.903324] zbud: loaded [ 2.907258] *** VALIDATE nfs *** [ 2.908459] *** VALIDATE nfs4 *** [ 2.909918] pstore: using deflate compression [ 2.913340] Platform Keyring initialized [ 3.062619] NET: Registered protocol family 38 [ 3.065845] Key type asymmetric registered [ 3.067435] Asymmetric key parser 'x509' registered [ 3.069901] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.073339] io scheduler mq-deadline registered [ 3.075286] io scheduler kyber registered [ 3.076859] io scheduler bfq registered [ 3.079143] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.082554] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.085528] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.088092] ACPI: Power Button [PWRF] [ 3.093929] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.100822] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.122072] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.152285] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.182046] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.187123] Non-volatile memory driver v1.3 [ 3.189143] Linux agpgart interface v0.103 [ 3.216585] virtio_blk virtio1: [vda] 134848 512-byte logical blocks (69.0 MB/65.8 MiB) [ 3.219475] vda: detected capacity change from 0 to 69042176 [ 3.236868] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.239605] vdb: detected capacity change from 0 to 1073741824 [ 3.250299] libphy: Fixed MDIO Bus: probed [ 3.255547] usbcore: registered new interface driver usbserial_generic [ 3.258263] usbserial: USB Serial support registered for generic [ 3.260982] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.266199] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.268425] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.270927] mousedev: PS/2 mouse device common for all mice [ 3.274266] rtc_cmos 00:05: RTC can wake from S4 [ 3.277573] rtc_cmos 00:05: registered as rtc0 [ 3.279452] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.284489] intel_pstate: CPU model not supported [ 3.287615] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.288711] hid: raw HID events driver (C) Jiri Kosina [ 3.296566] usbcore: registered new interface driver usbhid [ 3.298800] usbhid: USB HID core driver [ 3.300709] drop_monitor: Initializing network drop monitor service [ 3.305267] Initializing XFRM netlink socket [ 3.307983] NET: Registered protocol family 10 [ 3.312600] Segment Routing with IPv6 [ 3.313986] NET: Registered protocol family 17 [ 3.316909] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.318580] mpls_gso: MPLS GSO support [ 3.329696] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.334819] RAS: Correctable Errors collector initialized. [ 3.338134] AVX version of gcm_enc/dec engaged. [ 3.342805] AES CTR mode by8 optimization enabled [ 3.425472] sched_clock: Marking stable (3425454697, 0)->(4309167074, -883712377) [ 3.429264] registered taskstats version 1 [ 3.431591] Loading compiled-in X.509 certificates [ 3.433868] zswap: loaded using pool lzo/zbud [ 3.461644] Key type big_key registered [ 3.475555] Key type encrypted registered [ 3.477039] ima: No TPM chip found, activating TPM-bypass! [ 3.479015] ima: Allocated hash algorithm: sha1 [ 3.481604] ima: No architecture policies found [ 3.483169] evm: Initialising EVM extended attributes: [ 3.485356] evm: security.selinux [ 3.486744] evm: security.ima [ 3.488166] evm: security.capability [ 3.489734] evm: HMAC attrs: 0x1 [ 3.494123] rtc_cmos 00:05: setting system clock to 2026-06-01 14:23:25 UTC (1780323805) [ 3.502629] debug: unmapping init [mem 0xffffffff8de03000-0xffffffff8dffffff] [ 3.506409] debug: unmapping init [mem 0xffffffff8cb82000-0xffffffff8ce58fff] [ 3.513086] Write protecting the kernel read-only data: 28672k [ 3.517065] debug: unmapping init [mem 0xffffffff8b203000-0xffffffff8b3fffff] [ 3.520186] debug: unmapping init [mem 0xffffffff8bb14000-0xffffffff8bbfffff] [ 3.566219] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 3.574319] systemd[1]: Detected virtualization kvm. [ 3.576272] systemd[1]: Detected architecture x86-64. [ 3.577980] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.602392] systemd[1]: No hostname configured. [ 3.604623] systemd[1]: Set hostname to . [ 3.606919] random: systemd: uninitialized urandom read (16 bytes read) [ 3.609589] systemd[1]: Initializing machine ID from random generator. [ 3.668566] random: ln: uninitialized urandom read (6 bytes read) [ 3.767763] random: systemd: uninitialized urandom read (16 bytes read) [ 3.770853] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 3.777576] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 3.782141] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ OK ] Reached target Local File Systems. [ OK ] Reached target Swap. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Initrd Root Device. [ OK ] Listening on Journal Socket. Starting Create list of required st…ce nodes for the current kernel... Starting Setup Virtual Console... Starting Apply Kernel Variables... [ OK ] Reached target Local Encrypted Volumes. Starting Create Volatile Files and Directories... [ OK ] Started Memstrack Anylazing Service. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Paths. [ OK ] Reached target Sockets. Starting Journal Service... [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.532773] device-mapper: uevent: version 1.0.3 [ 4.535335] 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.[ 5.184857] random: fast init done [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 5.255958] virtio_net virtio0 ens2: renamed from eth0 [ 5.297481] scsi host0: ata_piix [ 5.383960] scsi host1: ata_piix [ 5.386559] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.390070] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.986303] dracut-initqueue[574]: RTNETLINK answers: File exists [ 10.076879] random: crng init done [ 10.079507] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 11.775053] 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... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Swap. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Local File Systems. Stopping udev Kernel Device Manager... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 13.110950] printk: systemd: 26 output lines suppressed due to ratelimiting [ 13.463735] SELinux: Disabled at runtime. [ 13.529135] 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) [ 13.538241] systemd[1]: Detected virtualization kvm. [ 13.539927] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 14.120685] systemd[1]: initrd-switch-root.service: Succeeded. [ 14.133971] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 14.141461] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 14.144987] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 14.151837] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 14.169709] systemd[1]: Starting Journal Service... Starting Journal Service... [ 14.184063] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ OK ] Listening on udev Kernel Socket. Mounting Huge Pages File System... [ OK ] Created slice User and Session Slice. Starting Remount Root and Kernel File Systems... Starting udev Coldplug all Devices... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Reached target rpc_pipefs.target. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Reached target Slices. Mounting Kernel Debug File System... [ OK ] Stopped target Initrd Root File System. [ OK ] Created slice system-getty.slice. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on Process Core Dump Socket. [ OK [0[ 14.529534] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS m] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. Starting Apply Kernel Variables... Starting Create list of required st…ce nodes for the current kernel... Mounting POSIX Message Queue File System... [ OK ] Started Journal Service. [ OK ] Mounted Huge Pages File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Kernel Debug File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started udev Coldplug all Devices. [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 15.849827] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 16.363415] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 16.430433] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 16.591893] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 16.649446] EDAC sbridge: Ver: 1.1.2 [ 18.509256] Key type dns_resolver registered [ 18.866171] NFS: Registering the id_resolver key type [ 18.868890] Key type id_resolver registered [ 18.870755] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... Starting Rebuild Dynamic Linker Cache... [ 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 ] Started dnf makecache --timer. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Reached target Basic System. Starting Login Service... Starting Restore /run/initramfs on shutdown... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Reached target sshd-keygen.target. [ OK ] Started irqbalance daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting Network Manager Wait Online... 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 Getty on tty1. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting System Logging Service... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg429-client login: [ 81.754832] libcfs: loading out-of-tree module taints kernel. [ 81.827058] Key type ._llcrypt registered [ 81.845827] Key type .llcrypt registered [ 82.345790] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 82.359929] alg: No test for adler32 (adler32-zlib) [ 83.537177] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 84.039532] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 85.721173] Key type lgssc registered [ 86.844156] Lustre: Echo OBD driver; http://www.lustre.org/ [ 192.146689] Lustre: Mounted lustre-client [ 195.865837] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 205.404290] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing check_logdir /tmp/testlogs/ [ 208.247533] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing yml_node [ 211.346989] Lustre: DEBUG MARKER: Client: 2.17.53.29 [ 213.145428] Lustre: DEBUG MARKER: MDS: 2.17.53.29 [ 214.928663] Lustre: DEBUG MARKER: OSS: 2.17.53.29 [ 216.091596] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Mon Jun 1 10:26:57 EDT 2026 [ 218.081498] Lustre: lustre-OST0000-osc-ffff8dc005a00000: disconnect after 24s idle [ 227.204584] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 228.295574] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 229.977910] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 240.271894] Lustre: Unmounted lustre-client [ 318.746282] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 321.125921] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 334.677430] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 361.999551] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 10:29:23 (1780324163) [ 363.430757] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 364.586752] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 10:29:25 (1780324165) [ 370.213008] hrtimer: interrupt took 4000601 ns [ 383.144221] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 385.299303] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 396.522855] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 396.926498] Lustre: Mounted lustre-client [ 408.951393] LustreError: 14406:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc02024a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 409.033222] Lustre: Unmounted lustre-client [ 439.056231] Key type lgssc unregistered [ 439.240375] LNet: 14816:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 439.248562] LNetError: 14816:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 439.263218] LNet: Removed LNI 192.168.204.29@tcp [ 439.725668] Key type .llcrypt unregistered [ 439.728369] Key type ._llcrypt unregistered [ 449.232938] Key type ._llcrypt registered [ 449.235947] Key type .llcrypt registered [ 450.120051] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 10:30:51 (1780324251) [ 450.954415] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 450.968517] alg: No test for adler32 (adler32-zlib) [ 451.902415] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 452.040689] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 453.680190] Key type lgssc registered [ 454.579255] Lustre: Echo OBD driver; http://www.lustre.org/ [ 505.293504] Lustre: Mounted lustre-client [ 589.474549] LustreError: 18149:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc00517b800: lov tgt 1990 not cleaned! deathrow=0, lovrc=1 [ 589.523319] Lustre: Unmounted lustre-client [ 607.645204] Key type lgssc unregistered [ 607.784671] LNet: 18612:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 607.789942] LNetError: 18612:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 607.807413] LNet: Removed LNI 192.168.204.29@tcp [ 608.181275] Key type .llcrypt unregistered [ 608.184892] Key type ._llcrypt unregistered [ 613.471488] Key type ._llcrypt registered [ 613.472877] Key type .llcrypt registered [ 614.405158] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 10:33:35 (1780324415) [ 616.005491] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 616.017252] alg: No test for adler32 (adler32-zlib) [ 616.955492] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 617.081761] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 618.720947] Key type lgssc registered [ 619.372638] Lustre: Echo OBD driver; http://www.lustre.org/ [ 646.561619] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 648.626739] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 658.559874] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 665.090449] Lustre: Mounted lustre-client [ 674.849745] LustreError: 21710:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc01882f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 674.903478] Lustre: Unmounted lustre-client [ 699.717145] Key type lgssc unregistered [ 699.874873] LNet: 22113:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 699.882754] LNetError: 22113:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 699.893987] LNet: Removed LNI 192.168.204.29@tcp [ 700.294981] Key type .llcrypt unregistered [ 700.296339] Key type ._llcrypt unregistered [ 705.618765] Key type ._llcrypt registered [ 705.620879] Key type .llcrypt registered [ 706.369448] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 10:35:07 (1780324507) [ 707.627120] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 707.635818] alg: No test for adler32 (adler32-zlib) [ 708.593814] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 708.725073] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 710.349253] Key type lgssc registered [ 711.118162] Lustre: Echo OBD driver; http://www.lustre.org/ [ 738.331386] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 740.519750] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 751.370968] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 753.724895] Lustre: Mounted lustre-client [ 760.946163] LustreError: 24804:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc005c88800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 760.997964] Lustre: Unmounted lustre-client [ 789.731602] Key type lgssc unregistered [ 789.903698] LNet: 25208:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 789.908559] LNetError: 25208:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 789.925830] LNet: Removed LNI 192.168.204.29@tcp [ 790.333056] Key type .llcrypt unregistered [ 790.338967] Key type ._llcrypt unregistered [ 798.379803] Key type ._llcrypt registered [ 798.381476] Key type .llcrypt registered [ 799.156620] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 10:36:40 (1780324600) [ 800.356608] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 800.374765] alg: No test for adler32 (adler32-zlib) [ 801.353454] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 801.506756] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 803.128204] Key type lgssc registered [ 803.813677] Lustre: Echo OBD driver; http://www.lustre.org/ [ 829.836521] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 831.663681] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 842.198542] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 844.564859] Lustre: Mounted lustre-client [ 860.137392] Lustre: lustre-MDT0000-mdc-ffff8dc01882a000: Connection to lustre-MDT0000 (at 192.168.204.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 865.254666] Lustre: lustre-MDT0001-mdc-ffff8dc01882a000: Connection to lustre-MDT0001 (at 192.168.204.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 871.894229] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 881.632563] Lustre: 26168:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1780324667/real 1780324667] req@00000000cc60b65a x1866805650922112/t0(0) o400->MGC192.168.204.129@tcp@192.168.204.129@tcp:26/25 lens 224/224 e 0 to 1 dl 1780324683 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 881.652353] LustreError: MGC192.168.204.129@tcp: Connection to MGS (at 192.168.204.129@tcp) was lost; in progress operations using this service will fail [ 882.180357] Lustre: Unmounted lustre-client [ 894.525802] Key type lgssc unregistered [ 894.680540] LNet: 28767:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 894.684261] LNetError: 28767:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 894.694583] LNet: Removed LNI 192.168.204.29@tcp [ 895.031104] Key type .llcrypt unregistered [ 895.032846] Key type ._llcrypt unregistered [ 901.919925] Key type ._llcrypt registered [ 901.921981] Key type .llcrypt registered [ 902.628040] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 10:38:23 (1780324703) [ 903.740949] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 903.776045] alg: No test for adler32 (adler32-zlib) [ 904.668618] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 904.791651] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 906.392476] Key type lgssc registered [ 906.947331] Lustre: Echo OBD driver; http://www.lustre.org/ [ 930.997224] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 932.551178] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 940.274069] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 946.682979] Lustre: Mounted lustre-client [ 951.780346] Lustre: lustre-OST0000-osc-ffff8dc020060800: Connection to lustre-OST0000 (at 192.168.204.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 956.125855] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 966.400713] Lustre: Unmounted lustre-client [ 985.553862] Key type lgssc unregistered [ 985.692585] LNet: 32286:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 985.697479] LNetError: 32286:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 985.710809] LNet: Removed LNI 192.168.204.29@tcp [ 985.989686] Key type .llcrypt unregistered [ 985.991230] Key type ._llcrypt unregistered [ 992.046920] Key type ._llcrypt registered [ 992.048764] Key type .llcrypt registered [ 992.559295] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 10:39:53 (1780324793) [ 993.446629] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 993.461846] alg: No test for adler32 (adler32-zlib) [ 994.345953] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 994.446207] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 996.048227] Key type lgssc registered [ 996.559920] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1015.791107] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1017.192357] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1024.324910] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1035.774446] Lustre: Mounted lustre-client [ 1037.836230] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1039.401410] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1050.092088] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-06-0110h40m51s [ 1050.989776] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1056.229413] Lustre: lustre-MDT0000-mdc-ffff8dc010929000: Connection to lustre-MDT0000 (at 192.168.204.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1058.861423] LustreError: 35973:0:(lmv_obd.c:1450:lmv_statfs()) lustre-MDT0000-mdc-ffff8dc010929000: can't stat MDS #0: rc = -19 [ 1059.462879] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1065.582666] Lustre: DEBUG MARKER: Wait for df (35973) ... [ 1066.195461] Lustre: DEBUG MARKER: done [ 1066.586061] LustreError: 36578:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc010929000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1066.589779] LustreError: MGC192.168.204.129@tcp: Connection to MGS (at 192.168.204.129@tcp) was lost; in progress operations using this service will fail [ 1066.604484] Lustre: Evicted from MGS (at 192.168.204.129@tcp) after server handle changed from 0x7ec37dc4f3d55327 to 0x7ec37dc4f3d556e6 [ 1066.610312] Lustre: MGC192.168.204.129@tcp: Connection restored to 192.168.204.129@tcp (at 192.168.204.129@tcp) [ 1066.615498] Lustre: Unmounted lustre-client [ 1080.928953] Key type lgssc unregistered [ 1081.058531] LNet: 36982:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1081.063759] LNetError: 36982:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1081.072500] LNet: Removed LNI 192.168.204.29@tcp [ 1081.340845] Key type .llcrypt unregistered [ 1081.342235] Key type ._llcrypt unregistered [ 1093.739864] Key type ._llcrypt registered [ 1093.741719] Key type .llcrypt registered [ 1094.304676] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 10:41:35 (1780324895) [ 1095.396214] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1095.405180] alg: No test for adler32 (adler32-zlib) [ 1096.279070] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 1096.374827] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 1097.970514] Key type lgssc registered [ 1098.484378] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1119.215071] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1120.780799] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1128.894957] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1131.167817] Lustre: Mounted lustre-client [ 1133.677496] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-06-0110h42m15s [ 1134.400518] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1137.724413] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1138.950903] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1159.136264] Lustre: 41030:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1780324941/real 1780324941] req@00000000186fd13d x1866805960250112/t0(0) o101->lustre-MDT0000-mdc-ffff8dc002daf000@192.168.204.129@tcp:12/10 lens 576/1152 e 0 to 1 dl 1780324961 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1159.149390] Lustre: lustre-MDT0000-mdc-ffff8dc002daf000: Connection to lustre-MDT0000 (at 192.168.204.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1159.167233] Lustre: lustre-MDT0000-mdc-ffff8dc002daf000: Connection restored to 192.168.204.129@tcp (at 192.168.204.129@tcp) [ 1179.868327] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1181.268541] Lustre: DEBUG MARKER: done [ 1182.679178] LustreError: 41504:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc002daf000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1182.717566] Lustre: Unmounted lustre-client [ 1209.523341] Key type lgssc unregistered [ 1209.656449] LNet: 41906:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1209.661391] LNetError: 41906:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1209.679109] LNet: Removed LNI 192.168.204.29@tcp [ 1209.998898] Key type .llcrypt unregistered [ 1210.000934] Key type ._llcrypt unregistered [ 1223.670823] Key type ._llcrypt registered [ 1223.672958] Key type .llcrypt registered [ 1224.315662] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 10:43:45 (1780325025) [ 1224.995823] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1225.713514] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 10:43:47 (1780325027) [ 1227.387788] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1227.393920] alg: No test for adler32 (adler32-zlib) [ 1228.308860] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 1228.406606] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 1230.008227] Key type lgssc registered [ 1230.549266] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1244.809703] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 10:44:06 (1780325046) [ 1258.758140] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1260.492402] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1268.867415] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1271.242666] Lustre: Mounted lustre-client [ 1273.438829] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 1274.531593] LustreError: 46233:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc0201fb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1274.586378] Lustre: Unmounted lustre-client [ 1285.560971] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 1303.682874] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1305.338965] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1305.624770] Lustre: Mounted lustre-client [ 1307.199375] LustreError: 47479:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc010c6f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1307.238759] Lustre: Unmounted lustre-client [ 1355.915397] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1358.035278] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1358.330675] Lustre: Mounted lustre-client [ 1360.076407] LustreError: 48591:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc002daa800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1360.129222] Lustre: Unmounted lustre-client [ 1373.693426] Lustre: DEBUG MARKER: files compared the same [ 1389.907519] Key type lgssc unregistered [ 1390.062927] LNet: 49295:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1390.066458] LNetError: 49295:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1390.076115] LNet: Removed LNI 192.168.204.29@tcp [ 1390.418137] Key type .llcrypt unregistered [ 1390.422808] Key type ._llcrypt unregistered [ 1396.679558] Key type ._llcrypt registered [ 1396.681387] Key type .llcrypt registered [ 1397.318086] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 10:46:38 (1780325198) [ 1398.308203] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1398.322220] alg: No test for adler32 (adler32-zlib) [ 1399.246173] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 1399.359931] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 1400.960210] Key type lgssc registered [ 1401.502394] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1425.074331] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1426.910062] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1435.103069] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1441.780773] Lustre: Mounted lustre-client [ 1443.597653] LustreError: 52377:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc01092d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1443.626821] Lustre: Unmounted lustre-client [ 1455.331385] Key type lgssc unregistered [ 1455.469105] LNet: 52777:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1455.473795] LNetError: 52777:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1455.483904] LNet: Removed LNI 192.168.204.29@tcp [ 1455.801902] Key type .llcrypt unregistered [ 1455.804292] Key type ._llcrypt unregistered [ 1462.967232] Key type ._llcrypt registered [ 1462.969477] Key type .llcrypt registered [ 1463.843234] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 10:47:44 (1780325264) [ 1464.921327] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1464.949110] alg: No test for adler32 (adler32-zlib) [ 1465.896397] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 1466.024861] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 1467.696168] Key type lgssc registered [ 1468.307523] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1532.550853] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1548.651187] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1550.605776] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1570.761767] Key type lgssc unregistered [ 1570.930083] LNet: 56166:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1570.933416] LNetError: 56166:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1570.941337] LNet: Removed LNI 192.168.204.29@tcp [ 1571.289071] Key type .llcrypt unregistered [ 1571.291080] Key type ._llcrypt unregistered [ 1578.447554] Key type ._llcrypt registered [ 1578.449285] Key type .llcrypt registered [ 1579.183166] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 10:49:40 (1780325380) [ 1580.929852] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1580.967047] alg: No test for adler32 (adler32-zlib) [ 1581.909177] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 1582.020507] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 1583.624895] Key type lgssc registered [ 1584.414500] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1619.451952] Lustre: Mounted lustre-client [ 1625.253256] LustreError: 58643:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc020524800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1625.293554] Lustre: Unmounted lustre-client [ 1656.858562] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 10:50:58 (1780325458) [ 1712.319326] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1719.784315] Lustre: Mounted lustre-client [ 1725.146503] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1725.149605] LustreError: 62585:0:(file.c:252:ll_close_inode_openhandle()) lustre-clilmv-ffff8dc011bfe800: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 1725.158776] Lustre: 57149:0:(llite_lib.c:4187:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.204.129@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 1735.417149] Lustre: Unmounted lustre-client [ 1773.282283] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 10:52:54 (1780325574) [ 1777.805028] Key type lgssc unregistered [ 1777.938133] LNet: 63798:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1777.941600] LNetError: 63798:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1777.950914] LNet: Removed LNI 192.168.204.29@tcp [ 1778.215702] Key type .llcrypt unregistered [ 1778.217105] Key type ._llcrypt unregistered [ 1782.900851] Key type ._llcrypt registered [ 1782.902164] Key type .llcrypt registered [ 1783.079094] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1783.087171] alg: No test for adler32 (adler32-zlib) [ 1783.972361] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 1784.075524] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 1785.665049] Key type lgssc registered [ 1786.241387] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1807.458817] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1808.896976] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1816.959805] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1827.832841] Lustre: Mounted lustre-client [ 1830.071834] LustreError: 66307:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc007577000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1830.117414] Lustre: Unmounted lustre-client [ 1849.072583] Key type lgssc unregistered [ 1849.208846] LNet: 66710:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1849.215124] LNetError: 66710:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1849.229458] LNet: Removed LNI 192.168.204.29@tcp [ 1849.558736] Key type .llcrypt unregistered [ 1849.560312] Key type ._llcrypt unregistered [ 1854.101604] Key type ._llcrypt registered [ 1854.103660] Key type .llcrypt registered [ 1854.291861] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1854.305660] alg: No test for adler32 (adler32-zlib) [ 1855.237751] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 1855.351188] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 1856.984182] Key type lgssc registered [ 1857.515418] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1903.465349] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1905.225624] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1914.298933] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1920.010800] Lustre: Mounted lustre-client [ 1920.983227] LustreError: 69633:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc01190b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1921.030411] Lustre: Unmounted lustre-client [ 1940.150901] Key type lgssc unregistered [ 1940.286666] LNet: 70034:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1940.291299] LNetError: 70034:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1940.307317] LNet: Removed LNI 192.168.204.29@tcp [ 1940.624729] Key type .llcrypt unregistered [ 1940.626515] Key type ._llcrypt unregistered [ 1947.713580] Key type ._llcrypt registered [ 1947.715618] Key type .llcrypt registered [ 1948.346332] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 10:55:49 (1780325749) [ 1949.243740] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1949.296992] alg: No test for adler32 (adler32-zlib) [ 1950.182564] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 1950.288346] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 1951.896180] Key type lgssc registered [ 1952.422644] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1975.583492] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1977.272399] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1985.789779] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1992.191638] Lustre: Mounted lustre-client [ 1995.044414] LustreError: 73142:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc011bfe000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1995.091653] Lustre: Unmounted lustre-client [ 1995.290338] Lustre: Mounted lustre-client [ 1996.458754] LustreError: 73217:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc0180fc800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1996.504976] Lustre: Unmounted lustre-client [ 2017.039279] Key type lgssc unregistered [ 2017.226388] LNet: 73618:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2017.235229] LNetError: 73618:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2017.251183] LNet: Removed LNI 192.168.204.29@tcp [ 2017.611759] Key type .llcrypt unregistered [ 2017.614188] Key type ._llcrypt unregistered [ 2023.613382] Key type ._llcrypt registered [ 2023.615074] Key type .llcrypt registered [ 2023.818745] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2023.825983] alg: No test for adler32 (adler32-zlib) [ 2024.718473] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 2024.829208] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 2026.432137] Key type lgssc registered [ 2027.006112] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2049.258661] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2050.769790] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2058.383030] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2068.982542] Lustre: Mounted lustre-client [ 2070.964350] LustreError: 76147:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc011909000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2071.004358] Lustre: Unmounted lustre-client [ 2089.886744] Key type lgssc unregistered [ 2090.014871] LNet: 76550:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2090.020376] LNetError: 76550:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2090.030552] LNet: Removed LNI 192.168.204.29@tcp [ 2090.341980] Key type .llcrypt unregistered [ 2090.343707] Key type ._llcrypt unregistered [ 2096.404213] Key type ._llcrypt registered [ 2096.405565] Key type .llcrypt registered [ 2096.994780] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 10:58:18 (1780325898) [ 2098.002807] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2098.017653] alg: No test for adler32 (adler32-zlib) [ 2098.922687] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 2099.027961] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 2100.624383] Key type lgssc registered [ 2101.172483] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2122.828713] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2124.375929] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2131.176319] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2137.592320] Lustre: Mounted lustre-client [ 2145.780753] Lustre: lustre: nosquash_nids is cleared [ 2145.788627] Lustre: lustre: root_squash is set to 500:500 [ 2153.009618] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.204.29@tcp 192.168.0.[2,10]@tcp [ 2162.158782] Lustre: lustre: nosquash_nids set to 192.168.204.29/24@tcp [ 2162.881730] LustreError: 80018:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc020526000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2162.912368] Lustre: Unmounted lustre-client [ 2180.579030] Key type lgssc unregistered [ 2180.707835] LNet: 80421:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2180.710492] LNetError: 80421:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2180.718651] LNet: Removed LNI 192.168.204.29@tcp [ 2180.969424] Key type .llcrypt unregistered [ 2180.971180] Key type ._llcrypt unregistered [ 2185.775201] Key type ._llcrypt registered [ 2185.776916] Key type .llcrypt registered [ 2186.228252] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 10:59:47 (1780325987) [ 2187.004365] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 2187.553906] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 10:59:49 (1780325989) [ 2188.288442] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2188.295962] alg: No test for adler32 (adler32-zlib) [ 2189.169250] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 2189.254813] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 2190.840172] Key type lgssc registered [ 2191.204928] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2208.829733] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2210.167046] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2216.754704] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2216.899244] Lustre: lustre: root_squash is set to 500:500 [ 2216.907331] Lustre: lustre: nosquash_nids set to 192.168.204.29/24@tcp [ 2227.189349] Lustre: Mounted lustre-client [ 2229.536847] LustreError: 83885:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc003fea000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2229.567124] Lustre: Unmounted lustre-client [ 2238.770992] Key type lgssc unregistered [ 2238.891533] LNet: 84286:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2238.894290] LNetError: 84286:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2238.904436] LNet: Removed LNI 192.168.204.29@tcp [ 2239.131890] Key type .llcrypt unregistered [ 2239.133720] Key type ._llcrypt unregistered [ 2244.048978] Key type ._llcrypt registered [ 2244.049992] Key type .llcrypt registered [ 2244.525912] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 2245.098429] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 11:00:46 (1780326046) [ 2251.242163] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2251.247523] alg: No test for adler32 (adler32-zlib) [ 2252.124100] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 2252.213382] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 2253.792135] Key type lgssc registered [ 2254.186275] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2284.126417] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2285.470365] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2292.842320] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2331.864665] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2333.097788] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2339.461908] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2352.974691] Lustre: Mounted lustre-client [ 2364.757572] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8dc005179800.ost_server_uuid 50 [ 2365.349698] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8dc005179800.ost_server_uuid in FULL state after 0 sec [ 2365.504390] Lustre: Mounted lustre-client [ 2366.401202] LustreError: 91164:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc00412d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2366.442598] Lustre: Unmounted lustre-client [ 2403.183235] Key type lgssc unregistered [ 2403.306565] LNet: 91736:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2403.311369] LNetError: 91736:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2403.321926] LNet: Removed LNI 192.168.204.29@tcp [ 2403.580653] Key type .llcrypt unregistered [ 2403.582392] Key type ._llcrypt unregistered [ 2414.687256] Key type ._llcrypt registered [ 2414.689255] Key type .llcrypt registered [ 2415.194221] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 11:03:36 (1780326216) [ 2421.517245] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2421.524626] alg: No test for adler32 (adler32-zlib) [ 2422.388643] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 2422.479699] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 2424.072168] Key type lgssc registered [ 2424.509109] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2453.832506] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2455.006286] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2462.501076] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2463.660805] Lustre: Mounted lustre-client [ 2478.883877] Lustre: lustre-OST0000-osc-ffff8dc009de4800: Connection to lustre-OST0000 (at 192.168.204.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2483.107309] Lustre: lustre-MDT0000-mdc-ffff8dc009de4800: Connection to lustre-MDT0000 (at 192.168.204.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2484.192176] Lustre: 93094:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1780326270/real 1780326270] req@00000000e777f707 x1866807350662272/t0(0) o400->lustre-OST0000-osc-ffff8dc009de4800@192.168.204.129@tcp:28/4 lens 224/224 e 0 to 1 dl 1780326286 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2489.312211] Lustre: 93094:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1780326275/real 1780326275] req@0000000031cf0860 x1866807350662784/t0(0) o400->lustre-OST0000-osc-ffff8dc009de4800@192.168.204.129@tcp:28/4 lens 224/224 e 0 to 1 dl 1780326291 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2498.530744] LustreError: MGC192.168.204.129@tcp: Connection to MGS (at 192.168.204.129@tcp) was lost; in progress operations using this service will fail [ 2498.536835] Lustre: Evicted from MGS (at 192.168.204.129@tcp) after server handle changed from 0xbb4021251a89be20 to 0xbb4021251a89c0ce [ 2498.541178] Lustre: MGC192.168.204.129@tcp: Connection restored to 192.168.204.129@tcp (at 192.168.204.129@tcp) [ 2499.326395] Lustre: lustre-MDT0000-mdc-ffff8dc009de4800: Connection restored to 192.168.204.129@tcp (at 192.168.204.129@tcp) [ 2499.691062] LustreError: 96890:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc009de4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2499.718311] Lustre: Unmounted lustre-client [ 2516.843394] Key type lgssc unregistered [ 2516.957107] LNet: 97292:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2516.959897] LNetError: 97292:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2516.966784] LNet: Removed LNI 192.168.204.29@tcp [ 2517.177960] Key type .llcrypt unregistered [ 2517.179175] Key type ._llcrypt unregistered [ 2521.942513] Key type ._llcrypt registered [ 2521.943543] Key type .llcrypt registered [ 2522.393091] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 11:05:23 (1780326323) [ 2533.996517] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2534.003682] alg: No test for adler32 (adler32-zlib) [ 2534.858621] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 2534.938195] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 2536.512202] Key type lgssc registered [ 2536.936913] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2551.295784] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2582.390276] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2583.540509] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2591.002907] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2597.337854] Lustre: Mounted lustre-client [ 2598.042864] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 2598.593773] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 11:06:40 (1780326400) [ 2650.762169] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2661.046155] Lustre: Unmounted lustre-client [ 2717.753764] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2718.881993] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2725.713966] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2748.442252] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 11:09:09 (1780326549) [ 2776.803787] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2777.983439] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2785.142457] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2790.383539] Lustre: Mounted lustre-client [ 2791.366195] LustreError: 115397:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc006812000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2791.394700] Lustre: Unmounted lustre-client [ 2805.548153] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 11:10:07 (1780326607) [ 2834.453763] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2835.556130] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2842.780415] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2842.935110] Lustre: Mounted lustre-client [ 2844.003347] LustreError: 119425:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc002dac800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2844.034128] Lustre: Unmounted lustre-client [ 2860.870990] Key type lgssc unregistered [ 2860.981462] LNet: 119828:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2860.983738] LNetError: 119828:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2860.991425] LNet: Removed LNI 192.168.204.29@tcp [ 2861.180161] Key type .llcrypt unregistered [ 2861.181482] Key type ._llcrypt unregistered [ 2865.958962] Key type ._llcrypt registered [ 2865.959981] Key type .llcrypt registered [ 2866.396114] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 11:11:07 (1780326667) [ 2867.234238] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2867.263489] alg: No test for adler32 (adler32-zlib) [ 2868.139302] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 2868.236990] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 2869.816191] Key type lgssc registered [ 2870.189264] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2886.342064] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2887.423668] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2893.281649] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2909.169681] Lustre: Mounted lustre-client [ 2919.856688] LustreError: 122929:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc0065b1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2919.880575] Lustre: Unmounted lustre-client [ 2936.773279] Key type lgssc unregistered [ 2936.884297] LNet: 123333:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2936.886341] LNetError: 123333:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2936.895367] LNet: Removed LNI 192.168.204.29@tcp [ 2937.096746] Key type .llcrypt unregistered [ 2937.098225] Key type ._llcrypt unregistered [ 2941.713869] Key type ._llcrypt registered [ 2941.714778] Key type .llcrypt registered [ 2942.113649] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 11:12:23 (1780326743) [ 2942.739575] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2942.785828] alg: No test for adler32 (adler32-zlib) [ 2943.647573] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 2943.733209] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 2945.320144] Key type lgssc registered [ 2945.751809] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2966.200570] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2968.495287] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2978.581565] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2986.481577] Lustre: Mounted lustre-client [ 2991.592289] Lustre: lustre-OST0000-osc-ffff8dc005c8f800: Connection to lustre-OST0000 (at 192.168.204.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3005.866708] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff8dc005c8f800.ost_server_uuid 50 [ 3006.492134] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8dc005c8f800.ost_server_uuid in DISCONN state after 0 sec [ 3007.189909] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 3017.825894] LustreError: 126996:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc005c8f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3017.865652] Lustre: Unmounted lustre-client [ 3037.751876] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 11:13:58 (1780326838) [ 3064.461251] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3067.248358] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3080.239462] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3093.008904] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3093.319273] Lustre: Mounted lustre-client [ 3098.600074] Lustre: lustre-OST0000-osc-ffff8dc003fed800: Connection to lustre-OST0000 (at 192.168.204.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3116.735283] LustreError: 129647:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc003fed800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3116.783101] Lustre: Unmounted lustre-client [ 3151.057221] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 11:15:52 (1780326952) [ 3168.205308] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3170.183370] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3179.927166] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3191.107770] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3191.510549] Lustre: Mounted lustre-client [ 3196.903930] Lustre: lustre-OST0000-osc-ffff8dc01190c000: Connection to lustre-OST0000 (at 192.168.204.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3210.287419] LustreError: 132391:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc01190c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3210.298575] LustreError: 132391:0:(lov_obd.c:792:lov_cleanup()) Skipped 1 previous similar message [ 3210.381959] Lustre: Unmounted lustre-client [ 3242.900743] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 11:17:24 (1780327044) [ 3308.902809] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3311.709319] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3329.608869] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3386.865723] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3389.853533] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3404.554682] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3428.302280] Lustre: Mounted lustre-client [ 3462.472398] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3475.192446] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 11 sec [ 3476.885791] LustreError: 139057:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc0045d0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3476.901861] LustreError: 139057:0:(lov_obd.c:792:lov_cleanup()) Skipped 1 previous similar message [ 3476.968838] Lustre: Unmounted lustre-client [ 3511.959555] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 11:21:53 (1780327313) [ 3528.267101] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3530.274575] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3540.527214] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3560.708969] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3576.470633] Lustre: Mounted lustre-client [ 3609.040896] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3609.997531] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 3612.650621] Lustre: lustre-OST0001-osc-ffff8dc002daa000: Connection to lustre-OST0001 (at 192.168.204.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3619.402564] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3629.730110] Lustre: Unmounted lustre-client [ 3675.876246] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 11:24:36 (1780327476) [ 3695.038959] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3697.406831] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3708.943904] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3709.293131] Lustre: Mounted lustre-client [ 3722.904458] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3723.892281] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 3731.727853] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8dc0035b1800.ost_server_uuid 50 [ 3732.659576] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8dc0035b1800.ost_server_uuid in FULL state after 0 sec [ 3734.487329] LustreError: 146037:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc0035b1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3734.546650] Lustre: Unmounted lustre-client [ 3734.744166] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 3734.750789] Lustre: Skipped 3 previous similar messages [ 3734.839385] Lustre: Mounted lustre-client [ 3735.589041] LustreError: 146093:0:(sec.c:413:import_sec_validate_get()) import 0000000005e0271d (NEW) with no sec [ 3777.637287] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 11:26:18 (1780327578) [ 3796.278465] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3798.648212] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3810.487691] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3821.803695] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3822.109477] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 3827.232239] Lustre: Mounted lustre-client [ 3837.613775] LustreError: 148932:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc006817800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3837.619785] LustreError: 148932:0:(lov_obd.c:792:lov_cleanup()) Skipped 2 previous similar messages [ 3837.681280] Lustre: Unmounted lustre-client [ 3837.684370] Lustre: Skipped 1 previous similar message [ 3866.646535] Key type lgssc unregistered [ 3866.808577] LNet: 149379:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3866.814875] LNetError: 149379:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3866.826956] LNet: Removed LNI 192.168.204.29@tcp [ 3867.216935] Key type .llcrypt unregistered [ 3867.219592] Key type ._llcrypt unregistered [ 3876.184252] Key type ._llcrypt registered [ 3876.187121] Key type .llcrypt registered [ 3877.005349] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 11:27:58 (1780327678) [ 3877.451119] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3877.521406] alg: No test for adler32 (adler32-zlib) [ 3878.467862] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 3878.599704] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 3880.216522] Key type lgssc registered [ 3880.870117] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3908.909490] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3910.729797] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3920.475180] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3930.163308] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3931.508394] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 3932.609976] Lustre: Mounted lustre-client [ 3935.680682] LustreError: lustre-MDT0001-mdc-ffff8dc005c89000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 3935.697247] Lustre: lustre-MDT0001-mdc-ffff8dc005c89000: Connection restored to 192.168.204.129@tcp (at 192.168.204.129@tcp) [ 3938.392922] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3939.341238] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 3955.703697] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 3957.853251] LustreError: 153596:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc005c89000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3957.889612] Lustre: Unmounted lustre-client [ 3979.984406] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 11:29:41 (1780327781) [ 4029.014419] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4034.288609] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4060.803963] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4061.732534] Lustre: Mounted lustre-client [ 4089.107442] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4091.280550] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 4107.742316] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4118.162518] Lustre: Unmounted lustre-client [ 4158.276254] Key type lgssc unregistered [ 4158.552300] LNet: 158722:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4158.555695] LNetError: 158722:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4158.573480] LNet: Removed LNI 192.168.204.29@tcp [ 4159.176584] Key type .llcrypt unregistered [ 4159.179628] Key type ._llcrypt unregistered [ 4196.595976] Key type ._llcrypt registered [ 4196.597833] Key type .llcrypt registered [ 4197.964407] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 11:33:18 (1780327998) [ 4200.405629] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4200.426298] alg: No test for adler32 (adler32-zlib) [ 4201.531595] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 4201.908915] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 4203.608183] Key type lgssc registered [ 4204.776388] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4253.149677] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4256.745694] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4274.754892] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4276.295750] Lustre: Mounted lustre-client [ 4322.033900] LustreError: 161992:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc010c6d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4322.141153] Lustre: Unmounted lustre-client [ 4360.860934] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4361.368640] Lustre: Mounted lustre-client [ 4398.233906] LustreError: 162644:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc009de3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4398.340446] Lustre: Unmounted lustre-client [ 4434.556217] Key type lgssc unregistered [ 4435.097524] LNet: 163052:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4435.107870] LNetError: 163052:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4435.144249] LNet: Removed LNI 192.168.204.29@tcp [ 4436.455962] Key type .llcrypt unregistered [ 4436.458242] Key type ._llcrypt unregistered [ 4454.932700] Key type ._llcrypt registered [ 4454.937019] Key type .llcrypt registered [ 4456.788433] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 11:37:36 (1780328256) [ 4460.200360] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4460.248534] alg: No test for adler32 (adler32-zlib) [ 4461.489891] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 4461.840348] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 4463.496190] Key type lgssc registered [ 4465.208709] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4525.480598] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4529.579667] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4550.749511] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4551.829720] Lustre: Mounted lustre-client [ 4568.568252] LustreError: 166316:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc00517b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4568.670595] Lustre: Unmounted lustre-client [ 4593.576997] Key type lgssc unregistered [ 4593.853889] LNet: 166719:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4593.873901] LNetError: 166719:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4594.921857] LNet: Removed LNI 192.168.204.29@tcp [ 4595.408602] Key type .llcrypt unregistered [ 4595.410662] Key type ._llcrypt unregistered [ 4604.340348] Key type ._llcrypt registered [ 4604.342015] Key type .llcrypt registered [ 4604.738520] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4604.788287] alg: No test for adler32 (adler32-zlib) [ 4605.844716] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 4606.040641] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 4607.704209] Key type lgssc registered [ 4608.647768] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4648.305743] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4651.835340] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4671.321955] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4677.187174] Lustre: Mounted lustre-client [ 4685.355372] LustreError: 169289:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc006811800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4685.438156] Lustre: Unmounted lustre-client [ 4709.870074] Key type lgssc unregistered [ 4710.143552] LNet: 169691:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4710.157260] LNetError: 169691:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4710.187287] LNet: Removed LNI 192.168.204.29@tcp [ 4710.888381] Key type .llcrypt unregistered [ 4710.898866] Key type ._llcrypt unregistered [ 4727.687639] Key type ._llcrypt registered [ 4727.691154] Key type .llcrypt registered [ 4729.061748] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 11:42:09 (1780328529) [ 4731.839919] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4731.863997] alg: No test for adler32 (adler32-zlib) [ 4733.021721] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 4733.275240] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 4734.976210] Key type lgssc registered [ 4736.255499] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4787.840645] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4791.469673] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4810.738506] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4813.615586] Lustre: Mounted lustre-client [ 4831.259692] LustreError: 172951:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc002daa800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4831.343491] Lustre: Unmounted lustre-client [ 4868.206576] Key type lgssc unregistered [ 4868.450729] LNet: 173357:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4868.455888] LNetError: 173357:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4868.476622] LNet: Removed LNI 192.168.204.29@tcp [ 4869.540128] Key type .llcrypt unregistered [ 4869.544672] Key type ._llcrypt unregistered [ 4881.446778] Key type ._llcrypt registered [ 4881.451581] Key type .llcrypt registered [ 4881.768868] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4881.783579] alg: No test for adler32 (adler32-zlib) [ 4882.868460] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 4883.096074] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 4884.784190] Key type lgssc registered [ 4886.134618] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4932.043234] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4934.958258] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4957.570935] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4959.371902] Lustre: Mounted lustre-client [ 4968.871378] LustreError: 175927:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc012ef0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4968.971256] Lustre: Unmounted lustre-client [ 5003.533588] Key type lgssc unregistered [ 5003.742446] LNet: 176331:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5003.748358] LNetError: 176331:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5003.772480] LNet: Removed LNI 192.168.204.29@tcp [ 5004.320530] Key type .llcrypt unregistered [ 5004.325868] Key type ._llcrypt unregistered [ 5018.260295] Key type ._llcrypt registered [ 5018.262849] Key type .llcrypt registered [ 5019.352147] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 11:47:00 (1780328820) [ 5038.331940] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5038.401095] alg: No test for adler32 (adler32-zlib) [ 5039.496535] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 5039.716107] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 5041.376166] Key type lgssc registered [ 5042.575461] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5106.318052] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5109.071273] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5124.348446] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5161.898333] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 11:49:23 (1780328963) [ 5178.173237] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5180.219645] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5189.462379] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5196.289646] Lustre: Mounted lustre-client [ 5202.639505] LustreError: 182836:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc002dac800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5202.684595] Lustre: Unmounted lustre-client [ 5231.608392] Key type lgssc unregistered [ 5231.749771] LNet: 183240:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5231.754910] LNetError: 183240:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5231.766051] LNet: Removed LNI 192.168.204.29@tcp [ 5232.055409] Key type .llcrypt unregistered [ 5232.056954] Key type ._llcrypt unregistered [ 5238.549337] Key type ._llcrypt registered [ 5238.550804] Key type .llcrypt registered [ 5239.167194] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 5239.945168] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 11:50:41 (1780329041) [ 5247.705398] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5247.742358] alg: No test for adler32 (adler32-zlib) [ 5248.640785] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 5248.747180] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 5250.344234] Key type lgssc registered [ 5250.983137] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5292.762727] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5294.293931] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5304.427228] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid [ 5312.280798] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid [ 5313.515982] Lustre: Mounted lustre-client [ 5322.418537] LustreError: 188759:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc007a1d000: lov tgt 1000 not cleaned! deathrow=0, lovrc=1 [ 5322.458991] Lustre: Unmounted lustre-client [ 5393.625472] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5395.136530] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5404.561810] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5427.565264] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 11:53:49 (1780329229) [ 5428.154537] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 5428.831355] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 11:53:50 (1780329230) [ 5475.386259] Key type lgssc unregistered [ 5475.510807] LNet: 194426:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5475.513692] LNetError: 194426:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5475.521490] LNet: Removed LNI 192.168.204.29@tcp [ 5475.760728] Key type .llcrypt unregistered [ 5475.762085] Key type ._llcrypt unregistered [ 5481.012777] Key type ._llcrypt registered [ 5481.014281] Key type .llcrypt registered [ 5481.490283] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 11:54:42 (1780329282) [ 5506.260932] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5506.317081] alg: No test for adler32 (adler32-zlib) [ 5507.241363] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 5507.351795] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 5508.960196] Key type lgssc registered [ 5509.511736] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5527.394987] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5528.667958] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5548.475779] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5560.531073] Key type lgssc unregistered [ 5560.657489] LNet: 198066:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5560.661184] LNetError: 198066:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5560.670461] LNet: Removed LNI 192.168.204.29@tcp [ 5560.936692] Key type .llcrypt unregistered [ 5560.938368] Key type ._llcrypt unregistered [ 5566.718089] Key type ._llcrypt registered [ 5566.719837] Key type .llcrypt registered [ 5567.251463] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 11:56:08 (1780329368) [ 5568.322813] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5568.332898] alg: No test for adler32 (adler32-zlib) [ 5569.229572] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 5569.330540] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 5570.936425] Key type lgssc registered [ 5571.407150] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5591.794237] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5593.108746] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5601.388368] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5607.933532] Lustre: Mounted lustre-client [ 5613.030255] Lustre: lustre-MDT0000-mdc-ffff8dc009de3000: Connection to lustre-MDT0000 (at 192.168.204.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5618.148246] Lustre: lustre-MDT0001-mdc-ffff8dc009de3000: Connection to lustre-MDT0001 (at 192.168.204.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5633.506257] LustreError: MGC192.168.204.129@tcp: Connection to MGS (at 192.168.204.129@tcp) was lost; in progress operations using this service will fail [ 5633.513266] Lustre: Evicted from MGS (at 192.168.204.129@tcp) after server handle changed from 0x1fde689fdcff20a4 to 0x1fde689fdcff39e8 [ 5633.516926] Lustre: MGC192.168.204.129@tcp: Connection restored to 192.168.204.129@tcp (at 192.168.204.129@tcp) [ 5640.163942] Lustre: 201079:0:(mgc_request.c:1899:mgc_process_log()) MGC192.168.204.129@tcp: IR log lustre-cliir failed, not fatal: rc = -2 [ 5651.399245] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5653.990843] LustreError: lustre-MDT0000-mdc-ffff8dc009de3000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 5654.010755] Lustre: lustre-MDT0000-mdc-ffff8dc009de3000: Connection restored to 192.168.204.129@tcp (at 192.168.204.129@tcp) [ 5655.087694] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 3 sec [ 5656.482176] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5657.140966] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 5657.858589] LustreError: 202481:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc009de3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5657.887448] Lustre: Unmounted lustre-client [ 5676.046485] Key type lgssc unregistered [ 5676.177726] LNet: 202883:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5676.180952] LNetError: 202883:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5676.190504] LNet: Removed LNI 192.168.204.29@tcp [ 5676.463265] Key type .llcrypt unregistered [ 5676.465506] Key type ._llcrypt unregistered [ 5682.324654] Key type ._llcrypt registered [ 5682.325923] Key type .llcrypt registered [ 5682.843499] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 11:58:04 (1780329484) [ 5683.734606] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5683.766751] alg: No test for adler32 (adler32-zlib) [ 5684.647403] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 5684.739427] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 5686.328209] Key type lgssc registered [ 5686.809324] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5705.862790] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5707.333843] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5731.867156] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5733.259539] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5747.979911] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5755.891375] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5776.145415] Key type lgssc unregistered [ 5776.273408] LNet: 207978:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5776.276263] LNetError: 207978:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5776.284433] LNet: Removed LNI 192.168.204.29@tcp [ 5776.550416] Key type .llcrypt unregistered [ 5776.551735] Key type ._llcrypt unregistered [ 5799.131246] Key type ._llcrypt registered [ 5799.132760] Key type .llcrypt registered [ 5799.608566] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 12:00:01 (1780329601) [ 5813.318114] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5813.334701] alg: No test for adler32 (adler32-zlib) [ 5814.202990] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 5814.298644] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 5815.888178] Key type lgssc registered [ 5816.292371] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5845.764794] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5847.067835] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5854.614489] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5892.209435] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 12:01:33 (1780329693) [ 5899.191850] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 12:01:39 (1780329699) [ 5971.461728] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5977.603845] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6008.944529] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6010.845256] Lustre: Mounted lustre-client [ 6017.428955] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [ 6019.182097] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 6020.909368] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 6022.632221] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [ 6026.220868] Lustre: lustre-MDT0000-mdc-ffff8dc002daf000: Connection to lustre-MDT0000 (at 192.168.204.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6042.594130] Lustre: 209870:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1780329828/real 1780329828] req@000000008f7eb114 x1866810907436160/t0(0) o400->MGC192.168.204.129@tcp@192.168.204.129@tcp:26/25 lens 224/224 e 0 to 1 dl 1780329844 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 6042.627218] LustreError: MGC192.168.204.129@tcp: Connection to MGS (at 192.168.204.129@tcp) was lost; in progress operations using this service will fail [ 6052.851364] Lustre: Evicted from MGS (at 192.168.204.129@tcp) after server handle changed from 0xa36499a2b93bc2d8 to 0xa36499a2b93bc44b [ 6052.860377] Lustre: MGC192.168.204.129@tcp: Connection restored to 192.168.204.129@tcp (at 192.168.204.129@tcp) [ 6059.328870] Lustre: lustre-MDT0000-mdc-ffff8dc002daf000: Connection restored to 192.168.204.129@tcp (at 192.168.204.129@tcp) [ 6067.678528] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6069.557988] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6073.871276] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [ 6078.443955] Lustre: lustre-MDT0000-mdc-ffff8dc002daf000: Connection to lustre-MDT0000 (at 192.168.204.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6094.816237] Lustre: 209871:0:(client.c:2480:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1780329880/real 1780329880] req@0000000031917c25 x1866810907445504/t0(0) o400->MGC192.168.204.129@tcp@192.168.204.129@tcp:26/25 lens 224/224 e 0 to 1 dl 1780329896 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 6094.854588] LustreError: MGC192.168.204.129@tcp: Connection to MGS (at 192.168.204.129@tcp) was lost; in progress operations using this service will fail [ 6101.333544] Lustre: lustre-MDT0000-mdc-ffff8dc002daf000: Connection restored to 192.168.204.129@tcp (at 192.168.204.129@tcp) [ 6104.429168] Lustre: Evicted from MGS (at 192.168.204.129@tcp) after server handle changed from 0xa36499a2b93bc44b to 0xa36499a2b93bc9f4 [ 6104.453840] Lustre: MGC192.168.204.129@tcp: Connection restored to 192.168.204.129@tcp (at 192.168.204.129@tcp) [ 6108.824332] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6110.033582] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6114.553952] LustreError: 218134:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc002daf000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6114.616553] Lustre: Unmounted lustre-client [ 6182.289960] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6186.129261] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6205.458932] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6206.511150] Lustre: Mounted lustre-client [ 6209.715539] LustreError: 219748:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc01092c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6209.849824] Lustre: Unmounted lustre-client [ 6260.219989] Key type lgssc unregistered [ 6260.605349] LNet: 220157:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6260.622450] LNetError: 220157:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6260.641781] LNet: Removed LNI 192.168.204.29@tcp [ 6261.415400] Key type .llcrypt unregistered [ 6261.418897] Key type ._llcrypt unregistered [ 6285.367656] Key type ._llcrypt registered [ 6285.370480] Key type .llcrypt registered [ 6287.145810] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 12:08:07 (1780330087) [ 6295.235035] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6295.280561] alg: No test for adler32 (adler32-zlib) [ 6296.715979] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 6297.331638] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 6299.160568] Key type lgssc registered [ 6300.896561] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6353.934418] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6356.973738] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6370.679339] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6377.505880] Lustre: Mounted lustre-client [ 6382.550349] LustreError: 223337:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc011908800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6382.621205] Lustre: Unmounted lustre-client [ 6429.622300] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6432.075065] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6444.307954] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6444.903572] Lustre: Mounted lustre-client [ 6452.415847] LustreError: 224925:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc011f1a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6452.473282] Lustre: Unmounted lustre-client [ 6496.183948] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6498.283297] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6509.029875] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6509.370963] Lustre: Mounted lustre-client [ 6510.352327] LustreError: 226529:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc00517a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6510.406975] Lustre: Unmounted lustre-client [ 6540.190556] Key type lgssc unregistered [ 6540.341734] LNet: 226934:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6540.346592] LNetError: 226934:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6540.360343] LNet: Removed LNI 192.168.204.29@tcp [ 6540.714278] Key type .llcrypt unregistered [ 6540.716974] Key type ._llcrypt unregistered [ 6548.943653] Key type ._llcrypt registered [ 6548.945015] Key type .llcrypt registered [ 6549.697254] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 12:12:30 (1780330350) [ 6551.573635] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6551.666520] alg: No test for adler32 (adler32-zlib) [ 6552.640273] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 6552.787973] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 6554.417422] Key type lgssc registered [ 6555.207582] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6582.257279] Key type lgssc unregistered [ 6582.413748] LNet: 229253:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6582.420521] LNetError: 229253:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6582.432697] LNet: Removed LNI 192.168.204.29@tcp [ 6582.808506] Key type .llcrypt unregistered [ 6582.812147] Key type ._llcrypt unregistered [ 6597.010758] Key type ._llcrypt registered [ 6597.014921] Key type .llcrypt registered [ 6597.243965] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6597.253840] alg: No test for adler32 (adler32-zlib) [ 6598.208938] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 6598.352700] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 6599.992248] Key type lgssc registered [ 6600.648843] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6643.260780] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6644.733653] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6652.982304] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6678.681663] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [ 6679.338645] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 12:14:40 (1780330480) [ 6690.247969] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6691.666025] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6698.924847] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6706.700735] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6707.898934] Lustre: Mounted lustre-client [ 6713.315947] Lustre: lustre-OST0001-osc-ffff8dc020060000: Connection to lustre-OST0001 (at 192.168.204.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6715.661953] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6725.959910] Lustre: Unmounted lustre-client [ 6750.536789] Key type lgssc unregistered [ 6750.722574] LNet: 236142:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6750.727398] LNetError: 236142:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6750.746102] LNet: Removed LNI 192.168.204.29@tcp [ 6751.150500] Key type .llcrypt unregistered [ 6751.152327] Key type ._llcrypt unregistered [ 6777.037785] Key type ._llcrypt registered [ 6777.040347] Key type .llcrypt registered [ 6778.268330] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 12:16:18 (1780330578) [ 6789.118235] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6789.193423] alg: No test for adler32 (adler32-zlib) [ 6790.380627] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 6790.582468] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 6792.248278] Key type lgssc registered [ 6793.176456] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6863.611534] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6870.234793] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6900.730104] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 12:18:21 (1780330701) [ 6934.539699] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6938.918793] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6958.004717] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6959.179569] Lustre: Mounted lustre-client [ 6965.604704] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 6977.281138] Lustre: Unmounted lustre-client [ 7079.925363] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7084.594411] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7141.997448] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7142.716926] Lustre: Mounted lustre-client [ 7146.223638] LustreError: 243221:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc005179000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7146.329455] Lustre: Unmounted lustre-client [ 7191.876559] Key type lgssc unregistered [ 7192.138823] LNet: 243628:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7192.147707] LNetError: 243628:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7192.174343] LNet: Removed LNI 192.168.204.29@tcp [ 7193.146935] Key type .llcrypt unregistered [ 7193.154080] Key type ._llcrypt unregistered [ 7226.266806] Key type ._llcrypt registered [ 7226.268709] Key type .llcrypt registered [ 7226.783577] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7226.808944] alg: No test for adler32 (adler32-zlib) [ 7227.996633] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 7228.248449] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 7229.952836] Key type lgssc registered [ 7231.361405] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7322.273965] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7326.899905] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7349.299320] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7405.445630] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [ 7407.108154] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 12:26:47 (1780331207) [ 7441.367508] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7445.563991] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7466.394600] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7471.308954] Lustre: Mounted lustre-client [ 7475.002704] LustreError: 249687:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc003fea000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7475.097561] Lustre: Unmounted lustre-client [ 7504.688743] Key type lgssc unregistered [ 7505.120668] LNet: 250090:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7505.124225] LNetError: 250090:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7505.144649] LNet: Removed LNI 192.168.204.29@tcp [ 7505.832073] Key type .llcrypt unregistered [ 7505.837507] Key type ._llcrypt unregistered [ 7524.190948] Key type ._llcrypt registered [ 7524.193118] Key type .llcrypt registered [ 7525.619959] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [ 7527.540764] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 12:28:47 (1780331327) [ 7537.409487] Key type .llcrypt unregistered [ 7537.413905] Key type ._llcrypt unregistered [ 7546.791757] Key type ._llcrypt registered [ 7546.795075] Key type .llcrypt registered [ 7547.462667] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7547.501742] alg: No test for adler32 (adler32-zlib) [ 7548.788780] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 7549.204591] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 7551.040224] Key type lgssc registered [ 7553.154257] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7612.993604] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7632.404962] Lustre: Mounted lustre-client [ 7635.801065] LustreError: 253112:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc00517c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7635.954283] Lustre: Unmounted lustre-client [ 7661.530964] Key type lgssc unregistered [ 7661.904769] LNet: 253516:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7661.911976] LNetError: 253516:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7661.937424] LNet: Removed LNI 192.168.204.29@tcp [ 7662.865132] Key type .llcrypt unregistered [ 7662.869806] Key type ._llcrypt unregistered [ 7681.654448] Key type ._llcrypt registered [ 7681.655897] Key type .llcrypt registered [ 7682.964285] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 12:31:23 (1780331483) [ 7686.010876] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7686.019102] alg: No test for adler32 (adler32-zlib) [ 7687.148173] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 7687.544504] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 7689.264467] Key type lgssc registered [ 7690.210017] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7773.270472] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7807.421197] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7810.905954] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7813.583767] Lustre: Mounted lustre-client [ 7816.422358] LustreError: 256572:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc0069e7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7816.503408] Lustre: Unmounted lustre-client [ 7853.414269] Key type lgssc unregistered [ 7853.671524] LNet: 256977:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7853.689551] LNetError: 256977:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7853.714485] LNet: Removed LNI 192.168.204.29@tcp [ 7854.345133] Key type .llcrypt unregistered [ 7854.351152] Key type ._llcrypt unregistered [ 7869.040516] Key type ._llcrypt registered [ 7869.043586] Key type .llcrypt registered [ 7870.714428] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 12:34:30 (1780331670) [ 7873.685351] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7873.813045] alg: No test for adler32 (adler32-zlib) [ 7875.022200] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 7875.293774] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 7877.048189] Key type lgssc registered [ 7878.275346] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7929.733612] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7932.813151] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7950.234289] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7951.773211] Lustre: Mounted lustre-client [ 7956.979990] Lustre: lustre-MDT0000-mdc-ffff8dc020255800: Connection to lustre-MDT0000 (at 192.168.204.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7962.599750] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7962.629172] LustreError: 260066:0:(file.c:6098:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [ 7963.097712] LustreError: 260085:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc020255800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7963.112415] LustreError: MGC192.168.204.129@tcp: Connection to MGS (at 192.168.204.129@tcp) was lost; in progress operations using this service will fail [ 7963.243333] Lustre: Unmounted lustre-client [ 7984.195805] Key type lgssc unregistered [ 7984.490494] LNet: 260479:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7984.505945] LNetError: 260479:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7984.532145] LNet: Removed LNI 192.168.204.29@tcp [ 7985.195671] Key type .llcrypt unregistered [ 7985.200494] Key type ._llcrypt unregistered [ 8000.682723] Key type ._llcrypt registered [ 8000.692153] Key type .llcrypt registered [ 8001.941073] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 12:36:42 (1780331802) [ 8004.387891] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8004.424663] alg: No test for adler32 (adler32-zlib) [ 8005.593831] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 8005.830717] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 8007.544190] Key type lgssc registered [ 8008.760559] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8052.675743] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8056.789910] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8075.520861] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8081.490612] Lustre: Mounted lustre-client [ 8086.507389] Lustre: lustre-MDT0001-mdc-ffff8dc011908000: Connection to lustre-MDT0001 (at 192.168.204.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8092.318601] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 8094.629601] LustreError: 263578:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc011908000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8094.747315] Lustre: Unmounted lustre-client [ 8116.762570] Key type lgssc unregistered [ 8117.079680] LNet: 263972:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8117.099870] LNetError: 263972:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8117.124271] LNet: Removed LNI 192.168.204.29@tcp [ 8118.007404] Key type .llcrypt unregistered [ 8118.012258] Key type ._llcrypt unregistered [ 8133.232030] Key type ._llcrypt registered [ 8133.235165] Key type .llcrypt registered [ 8134.671044] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 12:38:55 (1780331935) [ 8153.917350] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8153.934800] alg: No test for adler32 (adler32-zlib) [ 8155.082258] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 8155.403577] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 8157.097365] Key type lgssc registered [ 8158.597560] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8256.745672] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8258.298869] Lustre: Mounted lustre-client [ 8298.479905] Lustre: lustre-MDT0001-mdc-ffff8dc005c8e800: Connection to lustre-MDT0001 (at 192.168.204.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8305.717595] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 8316.052794] Lustre: Unmounted lustre-client [ 8349.530303] Key type lgssc unregistered [ 8349.811085] LNet: 269732:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8349.820907] LNetError: 269732:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8349.834756] LNet: Removed LNI 192.168.204.29@tcp [ 8350.371072] Key type .llcrypt unregistered [ 8350.374728] Key type ._llcrypt unregistered [ 8363.654641] Key type ._llcrypt registered [ 8363.656477] Key type .llcrypt registered [ 8364.944869] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 12:42:45 (1780332165) [ 8366.522584] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [ 8368.186581] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 12:42:49 (1780332169) [ 8369.881587] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [ 8371.587242] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 12:42:52 (1780332172) [ 8373.189977] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [ 8374.975550] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 12:42:55 (1780332175) [ 8376.316262] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [ 8377.926401] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 12:42:58 (1780332178) [ 8379.197342] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [ 8380.739941] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 12:43:01 (1780332181) [ 8389.498644] Key type .llcrypt unregistered [ 8389.501113] Key type ._llcrypt unregistered [ 8395.952753] Key type ._llcrypt registered [ 8395.958534] Key type .llcrypt registered [ 8396.374722] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8396.394515] alg: No test for adler32 (adler32-zlib) [ 8397.474389] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 8397.721597] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 8399.425291] Key type lgssc registered [ 8400.589846] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8464.085886] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8468.959347] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8491.315213] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8494.069835] Lustre: Mounted lustre-client [ 8496.509283] LustreError: 275327:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc009de3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8496.617564] Lustre: Unmounted lustre-client [ 8533.182605] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 12:45:33 (1780332333) [ 8563.828157] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8567.709990] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8598.804060] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8614.989844] Lustre: Mounted lustre-client [ 8617.308021] LustreError: 277449:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc0180e4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8617.404581] Lustre: Unmounted lustre-client [ 8661.920875] LNet: 1 local NIs in recovery (showing 1): 192.168.204.29@tcp [ 8668.402971] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 12:47:48 (1780332468) [ 9313.016109] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9317.220189] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9350.979639] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9448.310947] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 13:00:48 (1780333248) [ 9461.331270] Key type lgssc unregistered [ 9461.579894] LNet: 283016:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9461.593410] LNetError: 283016:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9461.621720] LNet: Removed LNI 192.168.204.29@tcp [ 9462.306691] Key type .llcrypt unregistered [ 9462.310701] Key type ._llcrypt unregistered [ 9602.242455] Key type ._llcrypt registered [ 9602.244430] Key type .llcrypt registered [ 9602.731933] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9602.762815] alg: No test for adler32 (adler32-zlib) [ 9603.819434] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 9604.055979] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 9605.720171] Key type lgssc registered [ 9606.953264] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9690.273104] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9693.589361] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9712.860442] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9743.012449] Key type lgssc unregistered [ 9743.205531] LNet: 286624:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9743.219368] LNetError: 286624:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9743.252446] LNet: Removed LNI 192.168.204.29@tcp [ 9743.840723] Key type .llcrypt unregistered [ 9743.844880] Key type ._llcrypt unregistered [ 9764.602260] Key type ._llcrypt registered [ 9764.611971] Key type .llcrypt registered [ 9766.061702] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 13:06:06 (1780333566) [ 9787.053123] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9787.082534] alg: No test for adler32 (adler32-zlib) [ 9788.090476] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 9788.288333] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 9789.976224] Key type lgssc registered [ 9790.975762] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9842.260185] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 13:07:22 (1780333642) [ 9853.735245] Key type lgssc unregistered [ 9853.967752] LNet: 290176:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9853.977637] LNetError: 290176:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9853.991908] LNet: Removed LNI 192.168.204.29@tcp [ 9854.567619] Key type .llcrypt unregistered [ 9854.571631] Key type ._llcrypt unregistered [ 9864.153509] Key type ._llcrypt registered [ 9864.156562] Key type .llcrypt registered [ 9864.388385] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9864.401634] alg: No test for adler32 (adler32-zlib) [ 9865.489279] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [ 9865.815472] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [ 9867.570184] Key type lgssc registered [ 9868.725815] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9926.576529] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9931.131150] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9954.419612] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10269.016764] LNet: Added LNI 192.168.204.29@tcp50 [8/256/0/180] [10271.463212] LNet: Removed LNI 192.168.204.29@tcp [10273.600213] Lustre: 294496:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff8dc0055d2800: config has no valid NIDs, force dynamic NIDs [10274.793648] Lustre: Mounted lustre-client [10274.851927] LustreError: 294506:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc0055d2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10274.951363] Lustre: Unmounted lustre-client [10278.086401] Key type lgssc unregistered [10278.433772] LNet: 294756:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10278.445757] LNetError: 294756:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [10279.464265] LNet: Removed LNI 192.168.204.29@tcp50 [10280.087420] Key type .llcrypt unregistered [10280.089603] Key type ._llcrypt unregistered [10299.942099] Key type ._llcrypt registered [10299.954535] Key type .llcrypt registered [10300.488622] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10300.505503] alg: No test for adler32 (adler32-zlib) [10301.694322] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [10301.986310] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [10303.696158] Key type lgssc registered [10305.324590] Lustre: Echo OBD driver; http://www.lustre.org/ [10365.781974] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10369.092987] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10377.169562] LNet: Added LNI 192.168.204.29@tcp50 [8/256/0/180] [10377.895897] LNet: Removed LNI 192.168.204.29@tcp [10379.032817] Lustre: 297389:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff8dc007a1f800: config has no valid NIDs, force dynamic NIDs [10380.251314] Lustre: Mounted lustre-client [10380.788494] LustreError: 297419:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc007a1f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10380.871430] Lustre: Unmounted lustre-client [10414.057289] Key type lgssc unregistered [10414.369964] LNet: 298258:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10414.381828] LNetError: 298258:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [10414.413304] LNet: Removed LNI 192.168.204.29@tcp50 [10414.865540] Key type .llcrypt unregistered [10414.869900] Key type ._llcrypt unregistered [10426.095438] Key type ._llcrypt registered [10426.098719] Key type .llcrypt registered [10427.504709] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 13:17:07 (1780334227) [10443.909980] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10443.950741] alg: No test for adler32 (adler32-zlib) [10445.055549] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [10445.327472] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [10447.043144] Key type lgssc registered [10448.189463] Lustre: Echo OBD driver; http://www.lustre.org/ [10525.602305] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10528.763942] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10549.879417] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10582.570920] Key type lgssc unregistered [10582.908299] LNet: 302913:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10582.916210] LNetError: 302913:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [10582.941798] LNet: Removed LNI 192.168.204.29@tcp [10583.541236] Key type .llcrypt unregistered [10583.543767] Key type ._llcrypt unregistered [10594.573507] Key type ._llcrypt registered [10594.578742] Key type .llcrypt registered [10595.770539] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 13:19:56 (1780334396) [10597.930985] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10597.979041] alg: No test for adler32 (adler32-zlib) [10599.035441] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [10599.272078] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [10600.960280] Key type lgssc registered [10602.064570] Lustre: Echo OBD driver; http://www.lustre.org/ [10651.827042] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10656.117487] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10680.654283] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10682.356818] Lustre: Mounted lustre-client [10737.581308] Lustre: Mounted fs15246-client [10752.740333] LustreError: 307078:0:(lov_obd.c:792:lov_cleanup()) fs15246-clilov-ffff8dc0065ee800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10752.823648] Lustre: Unmounted fs15246-client [10779.551991] LustreError: 307296:0:(lov_obd.c:792:lov_cleanup()) lustre-clilov-ffff8dc00517f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10779.628974] Lustre: Unmounted lustre-client [10816.036545] Key type lgssc unregistered [10816.309862] LNet: 307700:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10816.319269] LNetError: 307700:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [10816.336956] LNet: Removed LNI 192.168.204.29@tcp [10817.050154] Key type .llcrypt unregistered [10817.053448] Key type ._llcrypt unregistered [10829.786510] Key type ._llcrypt registered [10829.791985] Key type .llcrypt registered [10831.319784] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 13:23:51 (1780334631) [10832.933775] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10832.963902] alg: No test for adler32 (adler32-zlib) [10834.037963] Lustre: Lustre: Build Version: 2.17.53_29_g1eaec4e [10834.303383] LNet: Added LNI 192.168.204.29@tcp [8/256/0/180] [10835.960283] Key type lgssc registered [10837.351667] Lustre: Echo OBD driver; http://www.lustre.org/ [10967.506853] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10971.577986] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10994.388193] Lustre: DEBUG MARKER: oleg429-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [11106.747979] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 10889 sec ========= 13:28:27 (1780334907) [11108.440097] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 13:28:28 (1780334908) === [11111.730232] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 13:28:32 (1780334912) === [11147.045705] Key type lgssc unregistered [11147.358595] LNet: 316880:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11147.371369] LNetError: 316880:0:(acceptor.c:246:lnet_acceptor_remove_socket()) Interface ens2 not found [11147.386400] LNet: Removed LNI 192.168.204.29@tcp [11148.002100] Key type .llcrypt unregistered [11148.004080] Key type ._llcrypt unregistered