[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.17.0-8.fc42 06/10/2025 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 454188912 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2421 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE22BD 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 00227D (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2331 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE23C1 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE23F9 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe22bd-0xbffe2330] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22bc] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2331-0xbffe23c0] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe23c1-0xbffe23f8] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe23f9-0xbffe2420] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001010] APIC: Switch to symmetric I/O mode setup [ 0.002360] x2apic enabled [ 0.003009] Switched APIC routing to physical x2apic. [ 0.004014] kvm-guest: setup PV IPIs [ 0.007000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.007000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.007030] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.008013] pid_max: default: 32768 minimum: 301 [ 0.010154] LSM: Security Framework initializing [ 0.011088] Yama: becoming mindful. [ 0.012045] SELinux: Initializing. [ 0.013060] *** VALIDATE selinux *** [ 0.021749] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.025524] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.026140] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.027104] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029095] *** VALIDATE tmpfs *** [ 0.030430] *** VALIDATE proc *** [ 0.031216] *** VALIDATE cgroup *** [ 0.032007] *** VALIDATE cgroup2 *** [ 0.034074] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.035156] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036009] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.037026] Spectre V2 : User space: Vulnerable [ 0.038006] Speculative Store Bypass: Vulnerable [ 0.041598] debug: unmapping init [mem 0xffffffffb6e59000-0xffffffffb6e60fff] [ 0.043276] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.044718] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.045019] ... version: 2 [ 0.046011] ... bit width: 48 [ 0.047009] ... generic registers: 4 [ 0.048011] ... value mask: 0000ffffffffffff [ 0.049010] ... max period: 00007fffffffffff [ 0.050010] ... fixed-purpose events: 3 [ 0.051010] ... event mask: 000000070000000f [ 0.052290] rcu: Hierarchical SRCU implementation. [ 0.054457] smp: Bringing up secondary CPUs ... [ 0.055530] x86: Booting SMP configuration: [ 0.056024] .... node #0, CPUs: #1 #2 #3 [ 0.064139] smp: Brought up 1 node, 4 CPUs [ 0.066010] smpboot: Max logical packages: 1 [ 0.067009] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.092441] node 0 deferred pages initialised in 23ms [ 0.095668] devtmpfs: initialized [ 0.096334] x86/mm: Memory block size: 128MB [ 0.098856] gcov: version magic: 0x41383552 [ 0.100099] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.101071] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.102217] pinctrl core: initialized pinctrl subsystem [ 0.103159] [ 0.103604] ************************************************************* [ 0.104011] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.105007] ** ** [ 0.106006] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.107006] ** ** [ 0.108007] ** This means that this kernel is built to expose internal ** [ 0.109010] ** IOMMU data structures, which may compromise security on ** [ 0.110009] ** your system. ** [ 0.111006] ** ** [ 0.112007] ** If you see this message and you are not debugging the ** [ 0.113006] ** kernel, report this immediately to your vendor! ** [ 0.114005] ** ** [ 0.115007] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.116006] ************************************************************* [ 0.117655] NET: Registered protocol family 16 [ 0.118398] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.119046] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.120041] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.121302] cpuidle: using governor menu [ 0.122276] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.125587] PCI: Using configuration type 1 for base access [ 0.127193] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.136308] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.138016] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.140423] cryptd: max_cpu_qlen set to 1000 [ 0.141541] ACPI: Added _OSI(Module Device) [ 0.142000] ACPI: Added _OSI(Processor Device) [ 0.142009] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.143000] ACPI: Added _OSI(Processor Aggregator Device) [ 0.148833] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.155593] ACPI: Interpreter enabled [ 0.156076] ACPI: PM: (supports S0 S3 S4 S5) [ 0.157008] ACPI: Using IOAPIC for interrupt routing [ 0.158086] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.159537] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.165813] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.166026] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.167010] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.168060] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.169919] acpiphp: Slot [2] registered [ 0.170086] acpiphp: Slot [5] registered [ 0.170992] acpiphp: Slot [6] registered [ 0.171070] acpiphp: Slot [3] registered [ 0.171951] acpiphp: Slot [4] registered [ 0.172051] acpiphp: Slot [7] registered [ 0.172946] acpiphp: Slot [8] registered [ 0.173064] acpiphp: Slot [9] registered [ 0.173937] acpiphp: Slot [10] registered [ 0.174047] acpiphp: Slot [11] registered [ 0.174781] acpiphp: Slot [12] registered [ 0.175047] acpiphp: Slot [13] registered [ 0.175959] acpiphp: Slot [14] registered [ 0.176064] acpiphp: Slot [15] registered [ 0.176917] acpiphp: Slot [16] registered [ 0.177049] acpiphp: Slot [17] registered [ 0.177911] acpiphp: Slot [18] registered [ 0.178044] acpiphp: Slot [19] registered [ 0.178821] acpiphp: Slot [20] registered [ 0.179068] acpiphp: Slot [21] registered [ 0.179965] acpiphp: Slot [22] registered [ 0.180051] acpiphp: Slot [23] registered [ 0.180868] acpiphp: Slot [24] registered [ 0.181045] acpiphp: Slot [25] registered [ 0.181925] acpiphp: Slot [26] registered [ 0.182111] acpiphp: Slot [27] registered [ 0.182892] acpiphp: Slot [28] registered [ 0.183045] acpiphp: Slot [29] registered [ 0.183908] acpiphp: Slot [30] registered [ 0.184064] acpiphp: Slot [31] registered [ 0.184907] PCI host bridge to bus 0000:00 [ 0.185009] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.186010] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.187010] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.188012] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.189013] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.190013] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.191123] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.192658] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.193459] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.199526] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.202035] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.203014] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.206015] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.207014] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.210252] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.212805] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.214040] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.217863] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.222089] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.232012] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.236015] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.242277] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.246011] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.256017] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.269023] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.277733] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.284014] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.289013] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.306024] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.319714] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.321244] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.323473] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.326484] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.329201] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.334166] iommu: Default domain type: Passthrough [ 0.336433] SCSI subsystem initialized [ 0.338119] ACPI: bus type USB registered [ 0.339085] usbcore: registered new interface driver usbfs [ 0.341051] usbcore: registered new interface driver hub [ 0.342061] usbcore: registered new device driver usb [ 0.343153] pps_core: LinuxPPS API ver. 1 registered [ 0.345007] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.347042] PTP clock support registered [ 0.348274] EDAC MC: Ver: 3.0.0 [ 0.348745] PCI: Using ACPI for IRQ routing [ 0.349062] NetLabel: Initializing [ 0.349914] NetLabel: domain hash size = 128 [ 0.351008] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.352132] NetLabel: unlabeled traffic allowed by default [ 0.354286] vgaarb: loaded [ 0.355516] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.357007] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.363194] clocksource: Switched to clocksource kvm-clock [ 0.457229] VFS: Disk quotas dquot_6.6.0 [ 0.458777] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.461110] *** VALIDATE ramfs *** [ 0.462579] *** VALIDATE hugetlbfs *** [ 0.464046] pnp: PnP ACPI init [ 0.466457] pnp: PnP ACPI: found 6 devices [ 0.482505] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.485771] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.487990] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.490194] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.492793] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.495138] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.497991] NET: Registered protocol family 2 [ 0.500661] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.505953] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.509454] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.514837] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.518284] TCP: Hash tables configured (established 65536 bind 65536) [ 0.521082] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.523935] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.527182] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.530444] NET: Registered protocol family 1 [ 0.532962] RPC: Registered named UNIX socket transport module. [ 0.535635] RPC: Registered udp transport module. [ 0.538915] RPC: Registered tcp transport module. [ 0.541492] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.544707] NET: Registered protocol family 44 [ 0.546054] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.548050] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.550578] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.553072] PCI: CLS 0 bytes, default 64 [ 0.555721] Unpacking initramfs... [ 2.207740] debug: unmapping init [mem 0xffff96403cc64000-0xffff96403ffcffff] [ 2.211890] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.213928] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.216780] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.829232] Initialise system trusted keyrings [ 2.831026] Key type blacklist registered [ 2.833974] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.847043] zbud: loaded [ 2.850102] *** VALIDATE nfs *** [ 2.851157] *** VALIDATE nfs4 *** [ 2.852788] pstore: using deflate compression [ 2.857745] Platform Keyring initialized [ 3.108310] NET: Registered protocol family 38 [ 3.109645] Key type asymmetric registered [ 3.110855] Asymmetric key parser 'x509' registered [ 3.112400] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.115508] io scheduler mq-deadline registered [ 3.117222] io scheduler kyber registered [ 3.118993] io scheduler bfq registered [ 3.121382] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.123728] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.126105] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.130489] ACPI: Power Button [PWRF] [ 3.136707] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.143103] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.163697] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.197026] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.258053] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.268921] Non-volatile memory driver v1.3 [ 3.271990] Linux agpgart interface v0.103 [ 3.348558] virtio_blk virtio1: [vda] 134712 512-byte logical blocks (69.0 MB/65.8 MiB) [ 3.354503] vda: detected capacity change from 0 to 68972544 [ 3.397897] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.404223] vdb: detected capacity change from 0 to 1073741824 [ 3.417168] libphy: Fixed MDIO Bus: probed [ 3.428629] usbcore: registered new interface driver usbserial_generic [ 3.432908] usbserial: USB Serial support registered for generic [ 3.435251] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.439593] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.441359] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.443776] mousedev: PS/2 mouse device common for all mice [ 3.446623] rtc_cmos 00:05: RTC can wake from S4 [ 3.451849] rtc_cmos 00:05: registered as rtc0 [ 3.454817] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.457209] intel_pstate: CPU model not supported [ 3.460300] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.464437] hid: raw HID events driver (C) Jiri Kosina [ 3.466324] usbcore: registered new interface driver usbhid [ 3.469212] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.471387] usbhid: USB HID core driver [ 3.477449] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.481503] drop_monitor: Initializing network drop monitor service [ 3.484195] Initializing XFRM netlink socket [ 3.486407] NET: Registered protocol family 10 [ 3.489750] Segment Routing with IPv6 [ 3.491878] NET: Registered protocol family 17 [ 3.494368] mpls_gso: MPLS GSO support [ 3.504665] RAS: Correctable Errors collector initialized. [ 3.506741] AVX version of gcm_enc/dec engaged. [ 3.508147] AES CTR mode by8 optimization enabled [ 3.631811] sched_clock: Marking stable (3631683626, 0)->(4700429193, -1068745567) [ 3.634916] registered taskstats version 1 [ 3.636598] Loading compiled-in X.509 certificates [ 3.638497] zswap: loaded using pool lzo/zbud [ 3.676820] Key type big_key registered [ 3.816193] Key type encrypted registered [ 3.818539] ima: No TPM chip found, activating TPM-bypass! [ 3.821192] ima: Allocated hash algorithm: sha1 [ 3.823502] ima: No architecture policies found [ 3.825906] evm: Initialising EVM extended attributes: [ 3.828152] evm: security.selinux [ 3.829615] evm: security.ima [ 3.831081] evm: security.capability [ 3.832949] evm: HMAC attrs: 0x1 [ 3.836109] rtc_cmos 00:05: setting system clock to 2026-03-05 02:37:22 UTC (1772678242) [ 3.844500] debug: unmapping init [mem 0xffffffffb7e03000-0xffffffffb7ffffff] [ 3.850229] debug: unmapping init [mem 0xffffffffb6b82000-0xffffffffb6e58fff] [ 3.860346] Write protecting the kernel read-only data: 28672k [ 3.877800] debug: unmapping init [mem 0xffffffffb5203000-0xffffffffb53fffff] [ 3.883602] debug: unmapping init [mem 0xffffffffb5b14000-0xffffffffb5bfffff] [ 3.935570] 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.951854] systemd[1]: Detected virtualization kvm. [ 3.954583] systemd[1]: Detected architecture x86-64. [ 3.958595] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 4.002928] systemd[1]: No hostname configured. [ 4.011753] systemd[1]: Set hostname to . [ 4.016468] random: systemd: uninitialized urandom read (16 bytes read) [ 4.019895] systemd[1]: Initializing machine ID from random generator. [ 4.142486] random: ln: uninitialized urandom read (6 bytes read) [ 4.255927] random: systemd: uninitialized urandom read (16 bytes read) [ 4.258381] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ 4.266544] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ 4.280112] systemd[1]: Started Memstrack Anylazing Service. [ OK ] Started Memstrack Anylazing Service. [ OK ] Reached target Slices. [ OK ] Listening on Journal Socket (/dev/log). Starting Create list of required st…ce nodes for the current kernel... Starting Journal Service... Starting Setup Virtual Console... [ OK ] Reached target Initrd Root Device. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Reached target Swap. Starting Apply Kernel Variables... [ OK ] Reached target Paths. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Setup Virtual Console. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 5.491749] device-mapper: uevent: version 1.0.3 [ 5.493988] 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. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 7.431942] virtio_net virtio0 ens2: renamed from eth0 [ 7.803798] random: fast init done [ 7.817401] scsi host0: ata_piix [ 7.852304] scsi host1: ata_piix [ 7.853965] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 7.856706] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 12.373381] random: crng init done [ 12.380304] random: 7 urandom warning(s) missed due to ratelimiting [ 14.747735] dracut-initqueue[585]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 16.937587] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. Stopping udev Kernel Device Manager... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 19.624598] printk: systemd: 26 output lines suppressed due to ratelimiting [ 20.239599] SELinux: Disabled at runtime. [ 20.327508] 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) [ 20.341507] systemd[1]: Detected virtualization kvm. [ 20.343734] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 22.029919] systemd[1]: initrd-switch-root.service: Succeeded. [ 22.033910] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 22.045430] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 22.055637] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 22.062424] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 22.073367] systemd[1]: Starting Journal Service... Starting Journal Service... [ 22.097975] systemd[1]: Listening on Process Core Dump Socket. [ OK ] Listening on Process Core Dump Socket. [ OK ] Stopped target Switch Root. [ OK ] Created slice system-getty.slice. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Stopped target Initrd Root File System. Mounting Kernel Debug File System... Activating swap /dev/disk/by-label/SWAP... Starting Remount Root and Kernel File Systems... [ OK ] Reached target RPC Port Mapper. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ 22.268839] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Created slice User and Session Slice. Mounting Huge Pages File System... [ OK ] Listening on udev Control Socket. [ OK ] Reached target Slices. Starting Apply Kernel Variables... Mounting POSIX Message Queue File System... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Reached target rpc_pipefs.target. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ OK ] Stopped target Initrd File Systems. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on initctl Compatibility Named Pipe. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Started Journal Service. [ OK ] Mounted Kernel Debug File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Huge Pages File System. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started udev Coldplug all Devices. [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... [ OK ] Mounted /mnt. [ 24.411945] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 25.493441] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 25.703585] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 26.580142] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 27.763411] EDAC sbridge: Ver: 1.1.2 [* ] A start job is running for Configur…-only root support (8s / no limit) [** ] A start job is running for Configur…-only root support (8s / no limit)[ 31.161569] Key type dns_resolver registered [*** ] A start job is running for Configur…-only root support (9s / no limit)[ 31.672069] NFS: Registering the id_resolver key type [ 31.675222] Key type id_resolver registered [ 31.677620] Key type id_legacy registered [ *** ] A start job is running for Configur…only root support (10s / no limit) [ *** ] A start job is running for Configur…only root support (10s / no limit) [ 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 ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Reached target Basic System. Starting Restore /run/initramfs on shutdown... Starting Login Service... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Reached target sshd-keygen.target. [ OK ] Started irqbalance daemon. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting Network Manager Wait Online... [ OK ] Started Login Service. Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ OK ] Started Command Scheduler. [ 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 System Logging Service... Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg103-client login: [ 66.181152] libcfs: loading out-of-tree module taints kernel. [ 66.257233] Key type ._llcrypt registered [ 66.259035] Key type .llcrypt registered [ 66.479658] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 66.484926] alg: No test for adler32 (adler32-zlib) [ 67.463756] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 67.753792] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 69.368144] Key type lgssc registered [ 70.018440] Lustre: Echo OBD driver; http://www.lustre.org/ [ 142.618721] Lustre: Mounted lustre-client [ 145.548844] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 158.971177] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing check_logdir /tmp/testlogs/ [ 161.475938] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing yml_node [ 163.756193] Lustre: DEBUG MARKER: Client: 2.17.50.193 [ 165.141944] Lustre: DEBUG MARKER: MDS: 2.17.50.193 [ 166.421525] Lustre: DEBUG MARKER: OSS: 2.17.50.193 [ 167.254458] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Wed Mar 4 21:40:05 EST 2026 [ 168.417622] Lustre: lustre-OST0000-osc-ffff964084e68800: disconnect after 25s idle [ 176.145965] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 177.108819] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 178.516530] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 188.822437] Lustre: Unmounted lustre-client [ 265.406935] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 268.214757] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 282.975698] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 313.359466] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 21:42:31 (1772678551) [ 315.110945] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 316.706562] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 21:42:34 (1772678554) [ 339.466246] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 341.703186] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 353.886605] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 354.301381] Lustre: Mounted lustre-client [ 360.451810] LustreError: 14411:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff96408b5c5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 360.530311] Lustre: Unmounted lustre-client [ 383.138793] Key type lgssc unregistered [ 383.335449] LNet: 14820:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 383.340977] LNetError: 14820:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 383.355984] LNet: Removed LNI 192.168.201.3@tcp [ 383.844137] Key type .llcrypt unregistered [ 383.846951] Key type ._llcrypt unregistered [ 393.671340] Key type ._llcrypt registered [ 393.674146] Key type .llcrypt registered [ 394.609538] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 21:43:52 (1772678632) [ 395.556464] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 395.622584] alg: No test for adler32 (adler32-zlib) [ 396.613579] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 396.813966] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 398.472294] Key type lgssc registered [ 399.301628] Lustre: Echo OBD driver; http://www.lustre.org/ [ 450.259515] Lustre: Mounted lustre-client [ 535.714811] LustreError: 18149:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9640892f3800: lov tgt 1990 not cleaned! deathrow=0, lovrc=1 [ 535.741674] Lustre: Unmounted lustre-client [ 561.061723] Key type lgssc unregistered [ 561.270847] LNet: 18615:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 561.277774] LNetError: 18615:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 561.295078] LNet: Removed LNI 192.168.201.3@tcp [ 561.773218] Key type .llcrypt unregistered [ 561.775194] Key type ._llcrypt unregistered [ 567.067634] Key type ._llcrypt registered [ 567.069117] Key type .llcrypt registered [ 567.773213] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 21:46:45 (1772678805) [ 569.125904] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 569.176300] alg: No test for adler32 (adler32-zlib) [ 570.117653] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 570.251780] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 571.867559] Key type lgssc registered [ 572.452129] Lustre: Echo OBD driver; http://www.lustre.org/ [ 598.405076] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 600.477342] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 611.251537] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 613.625362] Lustre: Mounted lustre-client [ 622.913549] LustreError: 21717:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff964088b27800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 622.961651] Lustre: Unmounted lustre-client [ 649.248681] Key type lgssc unregistered [ 649.422283] LNet: 22122:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 649.426599] LNetError: 22122:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 649.436909] LNet: Removed LNI 192.168.201.3@tcp [ 649.864529] Key type .llcrypt unregistered [ 649.866389] Key type ._llcrypt unregistered [ 655.642663] Key type ._llcrypt registered [ 655.645606] Key type .llcrypt registered [ 656.490555] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 21:48:14 (1772678894) [ 657.901994] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 657.918988] alg: No test for adler32 (adler32-zlib) [ 658.878665] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 659.024852] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 660.664297] Key type lgssc registered [ 661.353660] Lustre: Echo OBD driver; http://www.lustre.org/ [ 688.838450] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 690.751949] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 700.075480] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 707.578613] Lustre: Mounted lustre-client [ 714.681269] LustreError: 24824:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff964083f04800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 714.748389] Lustre: Unmounted lustre-client [ 735.680273] Key type lgssc unregistered [ 735.885575] LNet: 25226:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 735.890623] LNetError: 25226:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 735.903219] LNet: Removed LNI 192.168.201.3@tcp [ 736.245505] Key type .llcrypt unregistered [ 736.247251] Key type ._llcrypt unregistered [ 743.615961] Key type ._llcrypt registered [ 743.618011] Key type .llcrypt registered [ 744.353870] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 21:49:42 (1772678982) [ 745.666466] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 745.697107] alg: No test for adler32 (adler32-zlib) [ 746.628810] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 746.802803] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 748.424800] Key type lgssc registered [ 749.170443] Lustre: Echo OBD driver; http://www.lustre.org/ [ 774.632530] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 776.699845] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 788.260804] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 789.573918] Lustre: Mounted lustre-client [ 799.719515] Lustre: lustre-MDT0000-mdc-ffff96408a5a7800: Connection to lustre-MDT0000 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 804.853579] Lustre: lustre-MDT0001-mdc-ffff96408a5a7800: Connection to lustre-MDT0001 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 811.824776] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 821.216409] Lustre: 26183:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772679043/real 1772679043] req@000000001179ee69 x1858788639381632/t0(0) o400->MGC192.168.201.103@tcp@192.168.201.103@tcp:26/25 lens 224/224 e 0 to 1 dl 1772679059 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 821.227791] LustreError: MGC192.168.201.103@tcp: Connection to MGS (at 192.168.201.103@tcp) was lost; in progress operations using this service will fail [ 822.149637] Lustre: Unmounted lustre-client [ 835.040878] Key type lgssc unregistered [ 835.186700] LNet: 28791:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 835.190244] LNetError: 28791:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 835.204369] LNet: Removed LNI 192.168.201.3@tcp [ 835.525504] Key type .llcrypt unregistered [ 835.527158] Key type ._llcrypt unregistered [ 842.487860] Key type ._llcrypt registered [ 842.489649] Key type .llcrypt registered [ 843.134867] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 21:51:21 (1772679081) [ 844.186806] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 844.202242] alg: No test for adler32 (adler32-zlib) [ 845.101960] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 845.220498] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 846.818722] Key type lgssc registered [ 847.357911] Lustre: Echo OBD driver; http://www.lustre.org/ [ 869.366636] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 871.023322] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 878.923286] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 889.333926] Lustre: Mounted lustre-client [ 894.441908] Lustre: lustre-OST0000-osc-ffff9640875f2800: Connection to lustre-OST0000 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 899.058910] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 909.381686] Lustre: Unmounted lustre-client [ 928.268574] Key type lgssc unregistered [ 928.400537] LNet: 32310:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 928.403775] LNetError: 32310:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 928.412944] LNet: Removed LNI 192.168.201.3@tcp [ 928.762531] Key type .llcrypt unregistered [ 928.764900] Key type ._llcrypt unregistered [ 935.377830] Key type ._llcrypt registered [ 935.379958] Key type .llcrypt registered [ 936.058819] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 21:52:53 (1772679173) [ 937.235465] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 937.258899] alg: No test for adler32 (adler32-zlib) [ 938.180217] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 938.315576] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 939.944362] Key type lgssc registered [ 940.674321] Lustre: Echo OBD driver; http://www.lustre.org/ [ 963.212842] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 964.821689] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 972.038675] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 982.517405] Lustre: Mounted lustre-client [ 984.630941] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 986.525889] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 997.347056] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-03-0421h53m55s [ 998.444635] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1002.979664] Lustre: lustre-MDT0000-mdc-ffff964083f02800: Connection to lustre-MDT0000 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1006.459691] LustreError: 36007:0:(lmv_obd.c:1434:lmv_statfs()) lustre-MDT0000-mdc-ffff964083f02800: can't stat MDS #0: rc = -19 [ 1007.167140] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1014.005180] Lustre: DEBUG MARKER: Wait for df (36007) ... [ 1014.245900] LustreError: MGC192.168.201.103@tcp: Connection to MGS (at 192.168.201.103@tcp) was lost; in progress operations using this service will fail [ 1014.252838] Lustre: Evicted from MGS (at 192.168.201.103@tcp) after server handle changed from 0xa405f1ba74de9690 to 0xa405f1ba74de9a4f [ 1014.260302] Lustre: MGC192.168.201.103@tcp: Connection restored to 192.168.201.103@tcp (at 192.168.201.103@tcp) [ 1014.687568] Lustre: DEBUG MARKER: done [ 1015.094491] LustreError: 36614:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff964083f02800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1015.126932] Lustre: Unmounted lustre-client [ 1041.497772] Key type lgssc unregistered [ 1041.622462] LNet: 37017:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1041.626245] LNetError: 37017:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1041.634811] LNet: Removed LNI 192.168.201.3@tcp [ 1041.902542] Key type .llcrypt unregistered [ 1041.904232] Key type ._llcrypt unregistered [ 1053.670833] Key type ._llcrypt registered [ 1053.671772] Key type .llcrypt registered [ 1054.276683] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 21:54:52 (1772679292) [ 1055.365185] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1055.376857] alg: No test for adler32 (adler32-zlib) [ 1056.277246] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 1056.392813] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 1057.992392] Key type lgssc registered [ 1058.449790] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1079.872931] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1081.463974] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1089.500812] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1090.712651] Lustre: Mounted lustre-client [ 1092.946457] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-03-0421h55m31s [ 1093.533104] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1096.348519] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1097.424807] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1117.664346] Lustre: 41068:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772679336/real 1772679336] req@00000000dd1e668e x1858788964439680/t0(0) o101->lustre-MDT0000-mdc-ffff964088f9f800@192.168.201.103@tcp:12/10 lens 576/1152 e 0 to 1 dl 1772679356 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1117.673414] Lustre: lustre-MDT0000-mdc-ffff964088f9f800: Connection to lustre-MDT0000 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1117.686899] Lustre: lustre-MDT0000-mdc-ffff964088f9f800: Connection restored to 192.168.201.103@tcp (at 192.168.201.103@tcp) [ 1138.155631] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1139.343279] Lustre: DEBUG MARKER: done [ 1140.504165] LustreError: 41545:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff964088f9f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1140.538359] Lustre: Unmounted lustre-client [ 1158.365310] Key type lgssc unregistered [ 1158.493450] LNet: 41948:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1158.496798] LNetError: 41948:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1158.503722] LNet: Removed LNI 192.168.201.3@tcp [ 1158.761764] Key type .llcrypt unregistered [ 1158.762838] Key type ._llcrypt unregistered [ 1171.529474] Key type ._llcrypt registered [ 1171.531094] Key type .llcrypt registered [ 1172.122895] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 21:56:50 (1772679410) [ 1172.763540] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1173.499347] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 21:56:51 (1772679411) [ 1174.983485] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1175.068177] alg: No test for adler32 (adler32-zlib) [ 1175.964428] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 1176.071526] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 1177.672119] Key type lgssc registered [ 1178.158864] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1190.719285] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 21:57:08 (1772679428) [ 1203.699342] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1205.289316] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1213.562117] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1214.799455] Lustre: Mounted lustre-client [ 1216.853120] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 1217.740532] LustreError: 46283:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff96408778f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1217.792162] Lustre: Unmounted lustre-client [ 1228.219698] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 1246.515178] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1248.001914] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1248.196379] Lustre: Mounted lustre-client [ 1249.347580] LustreError: 47537:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9640881f9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1249.394138] Lustre: Unmounted lustre-client [ 1278.772166] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1280.330255] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1280.621667] Lustre: Mounted lustre-client [ 1281.873914] LustreError: 48655:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff964088f9d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1281.917043] Lustre: Unmounted lustre-client [ 1294.642335] Lustre: DEBUG MARKER: files compared the same [ 1309.735409] Key type lgssc unregistered [ 1309.864624] LNet: 49361:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1309.867459] LNetError: 49361:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1309.877483] LNet: Removed LNI 192.168.201.3@tcp [ 1310.166068] Key type .llcrypt unregistered [ 1310.167939] Key type ._llcrypt unregistered [ 1315.709804] Key type ._llcrypt registered [ 1315.710682] Key type .llcrypt registered [ 1316.212643] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 21:59:14 (1772679554) [ 1317.067445] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1317.074792] alg: No test for adler32 (adler32-zlib) [ 1317.936798] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 1318.033189] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 1319.624170] Key type lgssc registered [ 1320.075666] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1338.933602] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1340.308653] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1346.698276] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1357.298787] Lustre: Mounted lustre-client [ 1358.951562] LustreError: 52445:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff96409037e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1358.978157] Lustre: Unmounted lustre-client [ 1376.250473] Key type lgssc unregistered [ 1376.359452] LNet: 52847:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1376.363758] LNetError: 52847:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1376.373651] LNet: Removed LNI 192.168.201.3@tcp [ 1376.571693] Key type .llcrypt unregistered [ 1376.572603] Key type ._llcrypt unregistered [ 1381.382361] Key type ._llcrypt registered [ 1381.383418] Key type .llcrypt registered [ 1381.834388] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 22:00:19 (1772679619) [ 1382.640203] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1382.649358] alg: No test for adler32 (adler32-zlib) [ 1383.505588] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 1383.589935] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 1385.168131] Key type lgssc registered [ 1385.560241] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1444.996883] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1454.995981] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1456.208900] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1469.543167] Key type lgssc unregistered [ 1469.663380] LNet: 56246:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1469.665822] LNetError: 56246:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1469.678284] LNet: Removed LNI 192.168.201.3@tcp [ 1469.923417] Key type .llcrypt unregistered [ 1469.925035] Key type ._llcrypt unregistered [ 1474.520188] Key type ._llcrypt registered [ 1474.521102] Key type .llcrypt registered [ 1474.907366] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 22:01:53 (1772679713) [ 1475.865472] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1475.905802] alg: No test for adler32 (adler32-zlib) [ 1476.780434] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 1476.870341] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 1478.464211] Key type lgssc registered [ 1478.911492] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1504.797336] Lustre: Mounted lustre-client [ 1510.170543] LustreError: 58723:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff964082a9c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1510.197153] Lustre: Unmounted lustre-client [ 1538.633427] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 22:02:56 (1772679776) [ 1578.102313] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1583.890770] Lustre: Mounted lustre-client [ 1589.212908] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1589.215543] LustreError: 62669:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff964082a99800: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 1589.225242] Lustre: 57227:0:(llite_lib.c:4187:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.201.103@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 1599.483403] Lustre: Unmounted lustre-client [ 1626.125518] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 22:04:24 (1772679864) [ 1629.946298] Key type lgssc unregistered [ 1630.055531] LNet: 63876:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1630.058776] LNetError: 63876:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1630.066632] LNet: Removed LNI 192.168.201.3@tcp [ 1630.285656] Key type .llcrypt unregistered [ 1630.287176] Key type ._llcrypt unregistered [ 1633.913021] Key type ._llcrypt registered [ 1633.914052] Key type .llcrypt registered [ 1634.093274] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1634.098879] alg: No test for adler32 (adler32-zlib) [ 1634.943752] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 1635.015502] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 1636.592112] Key type lgssc registered [ 1636.923915] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1653.647299] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1654.827229] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1660.940912] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1671.664448] Lustre: Mounted lustre-client [ 1673.221589] LustreError: 66393:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff96408415c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1673.245062] Lustre: Unmounted lustre-client [ 1682.121602] Key type lgssc unregistered [ 1682.224494] LNet: 66794:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1682.226577] LNetError: 66794:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1682.235619] LNet: Removed LNI 192.168.201.3@tcp [ 1682.463180] Key type .llcrypt unregistered [ 1682.464046] Key type ._llcrypt unregistered [ 1685.648133] Key type ._llcrypt registered [ 1685.648981] Key type .llcrypt registered [ 1685.843529] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1685.852828] alg: No test for adler32 (adler32-zlib) [ 1686.693266] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 1686.766394] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 1688.336127] Key type lgssc registered [ 1688.696568] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1743.125516] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1744.249229] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1749.806699] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1750.948731] Lustre: Mounted lustre-client [ 1751.337237] LustreError: 69720:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff96408a2d2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1751.368996] Lustre: Unmounted lustre-client [ 1768.594689] Key type lgssc unregistered [ 1768.715516] LNet: 70123:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1768.717925] LNetError: 70123:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1768.725649] LNet: Removed LNI 192.168.201.3@tcp [ 1768.949441] Key type .llcrypt unregistered [ 1768.950583] Key type ._llcrypt unregistered [ 1773.877818] Key type ._llcrypt registered [ 1773.878793] Key type .llcrypt registered [ 1774.352715] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 22:06:52 (1772680012) [ 1775.128633] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1775.135092] alg: No test for adler32 (adler32-zlib) [ 1775.993343] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 1776.071889] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 1777.648135] Key type lgssc registered [ 1777.982683] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1794.751217] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1796.006353] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1802.079602] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1817.581467] Lustre: Mounted lustre-client [ 1819.657608] LustreError: 73240:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9640892f7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1819.680429] Lustre: Unmounted lustre-client [ 1819.775774] Lustre: Mounted lustre-client [ 1820.297832] LustreError: 73317:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff964089207000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1820.323855] Lustre: Unmounted lustre-client [ 1837.103707] Key type lgssc unregistered [ 1837.204495] LNet: 73719:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1837.206256] LNetError: 73719:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1837.212734] LNet: Removed LNI 192.168.201.3@tcp [ 1837.404508] Key type .llcrypt unregistered [ 1837.405301] Key type ._llcrypt unregistered [ 1841.392545] Key type ._llcrypt registered [ 1841.395642] Key type .llcrypt registered [ 1841.598408] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1841.605173] alg: No test for adler32 (adler32-zlib) [ 1842.486591] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 1842.583846] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 1844.176381] Key type lgssc registered [ 1845.002834] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1879.007316] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1880.448838] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1888.591112] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1889.767891] Lustre: Mounted lustre-client [ 1900.457363] LustreError: 76253:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff964084195800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1900.489123] Lustre: Unmounted lustre-client [ 1927.087454] Key type lgssc unregistered [ 1927.235620] LNet: 76657:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1927.239542] LNetError: 76657:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1928.295047] LNet: Removed LNI 192.168.201.3@tcp [ 1928.574746] Key type .llcrypt unregistered [ 1928.576460] Key type ._llcrypt unregistered [ 1934.935071] Key type ._llcrypt registered [ 1934.936829] Key type .llcrypt registered [ 1935.573358] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 22:09:33 (1772680173) [ 1936.678228] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1936.691616] alg: No test for adler32 (adler32-zlib) [ 1937.567076] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 1937.664958] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 1939.256185] Key type lgssc registered [ 1939.739833] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1960.751120] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1962.304151] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1970.253660] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1981.953268] Lustre: Mounted lustre-client [ 1987.841594] Lustre: lustre: root_squash is set to 0:0 [ 1987.852966] Lustre: lustre: nosquash_nids is cleared [ 1997.826889] Lustre: lustre: root_squash is set to 500:500 [ 2003.526428] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.201.3@tcp 192.168.0.[2,10]@tcp [ 2009.454338] Lustre: lustre: nosquash_nids set to 192.168.201.3/24@tcp [ 2016.552996] LustreError: 80162:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff96408a676800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2016.603643] Lustre: Unmounted lustre-client [ 2032.427502] Key type lgssc unregistered [ 2032.591815] LNet: 80565:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2032.595289] LNetError: 80565:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2032.603599] LNet: Removed LNI 192.168.201.3@tcp [ 2032.976198] Key type .llcrypt unregistered [ 2032.977721] Key type ._llcrypt unregistered [ 2039.843721] Key type ._llcrypt registered [ 2039.845054] Key type .llcrypt registered [ 2040.407677] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 22:11:18 (1772680278) [ 2041.482901] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 2042.247179] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 22:11:20 (1772680280) [ 2043.272774] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2043.288486] alg: No test for adler32 (adler32-zlib) [ 2044.179221] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 2044.285702] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 2045.896153] Key type lgssc registered [ 2046.350052] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2065.897318] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2067.332253] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2075.111294] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2075.298157] Lustre: lustre: root_squash is set to 500:500 [ 2075.310423] Lustre: lustre: nosquash_nids set to 192.168.201.3/24@tcp [ 2085.881066] Lustre: Mounted lustre-client [ 2088.392324] LustreError: 84043:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff964085810000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2088.430382] Lustre: Unmounted lustre-client [ 2106.482560] Key type lgssc unregistered [ 2106.598487] LNet: 84448:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2106.600909] LNetError: 84448:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2106.610626] LNet: Removed LNI 192.168.201.3@tcp [ 2106.885251] Key type .llcrypt unregistered [ 2106.889938] Key type ._llcrypt unregistered [ 2113.030667] Key type ._llcrypt registered [ 2113.031794] Key type .llcrypt registered [ 2113.567877] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 2114.194357] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 22:12:32 (1772680352) [ 2120.507669] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2120.543606] alg: No test for adler32 (adler32-zlib) [ 2121.402219] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 2121.482504] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 2123.072149] Key type lgssc registered [ 2123.459997] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2151.869239] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2153.065840] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2160.392487] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2197.309357] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2198.639957] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2204.952450] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2218.933090] Lustre: Mounted lustre-client [ 2234.050417] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff964087611800.ost_server_uuid 50 [ 2234.512399] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff964087611800.ost_server_uuid in FULL state after 0 sec [ 2234.624938] Lustre: Mounted lustre-client [ 2235.272359] LustreError: 91330:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff96408a674000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2235.301087] Lustre: Unmounted lustre-client [ 2262.515853] Key type lgssc unregistered [ 2262.624482] LNet: 91903:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2262.626794] LNetError: 91903:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2262.633547] LNet: Removed LNI 192.168.201.3@tcp [ 2262.861064] Key type .llcrypt unregistered [ 2262.861893] Key type ._llcrypt unregistered [ 2272.176924] Key type ._llcrypt registered [ 2272.177723] Key type .llcrypt registered [ 2272.567826] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 22:15:10 (1772680510) [ 2277.899598] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2277.926461] alg: No test for adler32 (adler32-zlib) [ 2278.781923] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 2278.852977] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 2280.424159] Key type lgssc registered [ 2280.769124] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2307.375028] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2308.506437] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2315.492531] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2316.630776] Lustre: Mounted lustre-client [ 2331.179144] Lustre: lustre-OST0000-osc-ffff96409037e800: Connection to lustre-OST0000 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2336.162770] Lustre: lustre-MDT0000-mdc-ffff96409037e800: Connection to lustre-MDT0000 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2338.272176] Lustre: 93261:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772680560/real 1772680560] req@0000000060780d40 x1858790245799040/t0(0) o400->lustre-OST0000-osc-ffff96409037e800@192.168.201.103@tcp:28/4 lens 224/224 e 0 to 1 dl 1772680576 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2342.368121] Lustre: 93261:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772680565/real 1772680565] req@000000009efcfb91 x1858790245799552/t0(0) o400->lustre-OST0000-osc-ffff96409037e800@192.168.201.103@tcp:28/4 lens 224/224 e 0 to 1 dl 1772680581 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2346.316034] Lustre: lustre-OST0000-osc-ffff96409037e800: Connection restored to 192.168.201.103@tcp (at 192.168.201.103@tcp) [ 2346.465721] LustreError: MGC192.168.201.103@tcp: Connection to MGS (at 192.168.201.103@tcp) was lost; in progress operations using this service will fail [ 2346.471746] Lustre: Evicted from MGS (at 192.168.201.103@tcp) after server handle changed from 0xdd667738bb85c43c to 0xdd667738bb85c6f8 [ 2351.710336] Lustre: lustre-MDT0000-mdc-ffff96409037e800: Connection restored to 192.168.201.103@tcp (at 192.168.201.103@tcp) [ 2351.712377] Lustre: Skipped 1 previous similar message [ 2352.057196] LustreError: 97058:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff96409037e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2352.084960] Lustre: Unmounted lustre-client [ 2369.499964] Key type lgssc unregistered [ 2369.631483] LNet: 97460:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2369.633745] LNetError: 97460:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2369.644772] LNet: Removed LNI 192.168.201.3@tcp [ 2369.884585] Key type .llcrypt unregistered [ 2369.885846] Key type ._llcrypt unregistered [ 2374.484938] Key type ._llcrypt registered [ 2374.485756] Key type .llcrypt registered [ 2374.878933] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 22:16:53 (1772680613) [ 2386.325965] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2386.336878] alg: No test for adler32 (adler32-zlib) [ 2387.189610] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 2387.272550] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 2388.856216] Key type lgssc registered [ 2389.238113] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2403.708518] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2434.968097] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2436.153343] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2443.402571] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2444.561098] Lustre: Mounted lustre-client [ 2445.185121] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 2445.722951] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 22:18:03 (1772680683) [ 2496.804075] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2507.062053] Lustre: Unmounted lustre-client [ 2555.978813] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2557.124567] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2564.214529] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2585.509060] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 22:20:23 (1772680823) [ 2615.518586] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2616.672709] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2623.868152] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2624.033864] Lustre: Mounted lustre-client [ 2625.222163] LustreError: 115583:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9640840bd000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2625.252124] Lustre: Unmounted lustre-client [ 2643.495174] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 22:21:21 (1772680881) [ 2672.633696] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2673.794137] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2680.962016] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2681.121661] Lustre: Mounted lustre-client [ 2682.145200] LustreError: 119623:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff964086eec000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2682.173729] Lustre: Unmounted lustre-client [ 2707.517668] Key type lgssc unregistered [ 2707.628415] LNet: 120029:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2707.630759] LNetError: 120029:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2707.638853] LNet: Removed LNI 192.168.201.3@tcp [ 2707.863340] Key type .llcrypt unregistered [ 2707.864225] Key type ._llcrypt unregistered [ 2712.805980] Key type ._llcrypt registered [ 2712.806915] Key type .llcrypt registered [ 2713.228902] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 22:22:31 (1772680951) [ 2714.008370] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2714.022697] alg: No test for adler32 (adler32-zlib) [ 2714.879361] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 2714.957819] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 2716.544165] Key type lgssc registered [ 2716.910603] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2734.120040] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2735.270901] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2741.065400] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2756.591805] Lustre: Mounted lustre-client [ 2767.251865] LustreError: 123122:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff964084e6e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2767.278241] Lustre: Unmounted lustre-client [ 2784.470056] Key type lgssc unregistered [ 2784.592409] LNet: 123525:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2784.594622] LNetError: 123525:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2784.602683] LNet: Removed LNI 192.168.201.3@tcp [ 2784.832185] Key type .llcrypt unregistered [ 2784.833823] Key type ._llcrypt unregistered [ 2789.710795] Key type ._llcrypt registered [ 2789.711995] Key type .llcrypt registered [ 2790.147302] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 22:23:48 (1772681028) [ 2790.852201] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2790.912664] alg: No test for adler32 (adler32-zlib) [ 2791.766019] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 2791.856056] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 2793.440110] Key type lgssc registered [ 2793.813238] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2810.616895] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2811.803550] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2817.775450] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2824.154842] Lustre: Mounted lustre-client [ 2829.284479] Lustre: lustre-OST0000-osc-ffff964089c61000: Connection to lustre-OST0000 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2833.974214] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff964089c61000.ost_server_uuid 50 [ 2834.453571] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff964089c61000.ost_server_uuid in DISCONN state after 0 sec [ 2835.007758] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 2844.342187] LustreError: 127209:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff964089c61000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2844.367136] Lustre: Unmounted lustre-client [ 2855.044937] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 22:24:53 (1772681093) [ 2864.284784] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2865.398378] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2871.169384] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2878.365052] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 2878.531464] Lustre: Mounted lustre-client [ 2883.556053] Lustre: lustre-OST0000-osc-ffff96408a677800: Connection to lustre-OST0000 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2897.906886] LustreError: 129856:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff96408a677800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2897.933128] Lustre: Unmounted lustre-client [ 2920.823633] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 22:25:59 (1772681159) [ 2930.478779] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2931.734557] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2937.799236] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2943.632722] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 2943.812487] Lustre: Mounted lustre-client [ 2949.091958] Lustre: lustre-OST0000-osc-ffff9640881fb000: Connection to lustre-OST0000 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2960.463801] LustreError: 132604:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9640881fb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2960.466879] LustreError: 132604:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 2960.493129] Lustre: Unmounted lustre-client [ 2983.355345] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 22:27:01 (1772681221) [ 3012.378711] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3013.514708] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3020.657767] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3049.064649] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3050.197320] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3056.048879] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3079.049369] Lustre: Mounted lustre-client [ 3106.098294] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3106.603995] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 3106.911637] LustreError: 139190:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff96408a860800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3106.914733] LustreError: 139190:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 3106.936921] Lustre: Unmounted lustre-client [ 3120.924393] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 22:29:19 (1772681359) [ 3130.316187] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3131.405321] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3137.416487] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3158.464570] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3171.998580] Lustre: Mounted lustre-client [ 3199.033424] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3199.559198] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 3203.044828] Lustre: lustre-OST0001-osc-ffff9640892f5800: Connection to lustre-OST0001 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3207.131442] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3217.403177] Lustre: Unmounted lustre-client [ 3246.580973] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 22:31:24 (1772681484) [ 3256.065675] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3257.205919] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3263.652993] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3269.110627] Lustre: Mounted lustre-client [ 3276.895595] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3277.419521] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 3281.278938] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff964089c46800.ost_server_uuid 50 [ 3281.816738] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff964089c46800.ost_server_uuid in FULL state after 0 sec [ 3282.533199] LustreError: 146173:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff964089c46800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3282.562045] Lustre: Unmounted lustre-client [ 3282.660371] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 3282.663048] Lustre: Skipped 3 previous similar messages [ 3283.074698] LustreError: 146235:0:(sec.c:410:import_sec_validate_get()) import 000000008f1170b2 (NEW) with no sec [ 3314.647904] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 22:32:32 (1772681552) [ 3325.323622] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3326.463471] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3332.464437] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3338.192791] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3338.325894] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 3343.858394] Lustre: Mounted lustre-client [ 3343.859658] Lustre: Skipped 1 previous similar message [ 3346.313201] LustreError: 149044:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff96408b5ee800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3346.316610] LustreError: 149044:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 3346.350219] Lustre: Unmounted lustre-client [ 3346.351125] Lustre: Skipped 1 previous similar message [ 3370.805399] Key type lgssc unregistered [ 3370.917602] LNet: 149494:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3370.920763] LNetError: 149494:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3370.929897] LNet: Removed LNI 192.168.201.3@tcp [ 3371.189119] Key type .llcrypt unregistered [ 3371.190541] Key type ._llcrypt unregistered [ 3376.431429] Key type ._llcrypt registered [ 3376.432423] Key type .llcrypt registered [ 3376.976742] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 22:33:35 (1772681615) [ 3377.355538] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3377.397981] alg: No test for adler32 (adler32-zlib) [ 3378.251389] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 3378.341459] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 3379.928168] Key type lgssc registered [ 3380.292861] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3397.475483] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3398.604700] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3404.386078] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3410.355917] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3410.511953] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 3416.048548] Lustre: Mounted lustre-client [ 3417.834643] LustreError: lustre-MDT0001-mdc-ffff96408758b000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 3417.842442] Lustre: lustre-MDT0001-mdc-ffff96408758b000: Connection restored to 192.168.201.103@tcp (at 192.168.201.103@tcp) [ 3419.611255] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3420.120678] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 3435.497584] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 3437.188976] LustreError: 153711:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff96408758b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3437.214436] Lustre: Unmounted lustre-client [ 3456.166330] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 22:34:54 (1772681694) [ 3485.793176] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3486.981871] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3494.055702] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3494.198782] Lustre: Mounted lustre-client [ 3504.413245] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3505.097920] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 3519.461733] Lustre: lustre-OST0001-osc-ffff96408b5ef000: Connection to lustre-OST0001 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3522.464324] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3532.734616] Lustre: Unmounted lustre-client [ 3573.247570] Key type lgssc unregistered [ 3573.399997] LNet: 158835:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3573.403814] LNetError: 158835:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3573.415250] LNet: Removed LNI 192.168.201.3@tcp [ 3573.756747] Key type .llcrypt unregistered [ 3573.757863] Key type ._llcrypt unregistered [ 3585.105989] Key type ._llcrypt registered [ 3585.107171] Key type .llcrypt registered [ 3585.626433] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 22:37:03 (1772681823) [ 3586.382355] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3586.412059] alg: No test for adler32 (adler32-zlib) [ 3587.263453] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 3587.346035] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 3588.928167] Key type lgssc registered [ 3589.380481] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3606.021941] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3607.226077] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3613.248492] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3619.804078] Lustre: Mounted lustre-client [ 3657.084809] LustreError: 162099:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff96408a863800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3657.116844] Lustre: Unmounted lustre-client [ 3673.355808] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3673.522831] Lustre: Mounted lustre-client [ 3706.149306] LustreError: 162750:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff96408c433000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3706.175168] Lustre: Unmounted lustre-client [ 3723.878362] Key type lgssc unregistered [ 3724.018390] LNet: 163158:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3724.020774] LNetError: 163158:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3724.030195] LNet: Removed LNI 192.168.201.3@tcp [ 3724.292515] Key type .llcrypt unregistered [ 3724.294116] Key type ._llcrypt unregistered [ 3730.063735] Key type ._llcrypt registered [ 3730.065013] Key type .llcrypt registered [ 3730.622570] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 22:39:28 (1772681968) [ 3731.419682] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3731.451733] alg: No test for adler32 (adler32-zlib) [ 3732.325502] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 3732.432485] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 3734.048170] Key type lgssc registered [ 3734.584167] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3754.253092] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3755.449570] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3762.213368] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3772.913068] Lustre: Mounted lustre-client [ 3779.466350] LustreError: 166414:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff964082de9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3779.495177] Lustre: Unmounted lustre-client [ 3796.866285] Key type lgssc unregistered [ 3796.990432] LNet: 166817:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3796.993011] LNetError: 166817:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3797.000182] LNet: Removed LNI 192.168.201.3@tcp [ 3797.251884] Key type .llcrypt unregistered [ 3797.253259] Key type ._llcrypt unregistered [ 3801.530460] Key type ._llcrypt registered [ 3801.531452] Key type .llcrypt registered [ 3801.767588] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3801.772894] alg: No test for adler32 (adler32-zlib) [ 3802.667323] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 3802.758800] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 3804.352296] Key type lgssc registered [ 3804.818135] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3823.204927] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3824.760817] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3831.412998] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3842.041553] Lustre: Mounted lustre-client [ 3844.905181] LustreError: 169386:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff96408404b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3844.932133] Lustre: Unmounted lustre-client [ 3862.652479] Key type lgssc unregistered [ 3862.775441] LNet: 169790:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3862.778031] LNetError: 169790:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3862.785840] LNet: Removed LNI 192.168.201.3@tcp [ 3863.052882] Key type .llcrypt unregistered [ 3863.053825] Key type ._llcrypt unregistered [ 3868.320392] Key type ._llcrypt registered [ 3868.321579] Key type .llcrypt registered [ 3868.869521] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 22:41:46 (1772682106) [ 3869.634760] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3869.688448] alg: No test for adler32 (adler32-zlib) [ 3870.576061] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 3870.666257] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 3872.256207] Key type lgssc registered [ 3872.784179] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3890.957366] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3892.317420] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3898.368930] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3909.107598] Lustre: Mounted lustre-client [ 3915.729962] LustreError: 173040:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff964089c46800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3915.754069] Lustre: Unmounted lustre-client [ 3933.065700] Key type lgssc unregistered [ 3933.178490] LNet: 173442:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3933.180624] LNetError: 173442:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3933.187420] LNet: Removed LNI 192.168.201.3@tcp [ 3933.400299] Key type .llcrypt unregistered [ 3933.401914] Key type ._llcrypt unregistered [ 3937.599304] Key type ._llcrypt registered [ 3937.600829] Key type .llcrypt registered [ 3937.793205] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3937.799752] alg: No test for adler32 (adler32-zlib) [ 3938.646740] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 3938.722302] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 3940.304173] Key type lgssc registered [ 3940.730748] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3959.118297] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3960.483470] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3966.675861] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3982.319521] Lustre: Mounted lustre-client [ 3985.136338] LustreError: 176008:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff96408a863000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3985.164390] Lustre: Unmounted lustre-client [ 4010.734441] Key type lgssc unregistered [ 4010.857529] LNet: 176412:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4010.860212] LNetError: 176412:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4010.868409] LNet: Removed LNI 192.168.201.3@tcp [ 4011.138803] Key type .llcrypt unregistered [ 4011.140115] Key type ._llcrypt unregistered [ 4016.451097] Key type ._llcrypt registered [ 4016.452968] Key type .llcrypt registered [ 4017.018330] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 22:44:15 (1772682255) [ 4024.262634] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4024.294117] alg: No test for adler32 (adler32-zlib) [ 4025.157676] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 4025.236983] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 4026.816147] Key type lgssc registered [ 4027.236404] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4058.348091] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4059.698369] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4067.500993] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4098.081788] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 22:45:36 (1772682336) [ 4109.174257] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4110.580165] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4117.274986] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4127.730828] Lustre: Mounted lustre-client [ 4129.672717] LustreError: 182919:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9640892f2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4129.705153] Lustre: Unmounted lustre-client [ 4148.087138] Key type lgssc unregistered [ 4148.194620] LNet: 183321:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4148.197580] LNetError: 183321:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4148.208469] LNet: Removed LNI 192.168.201.3@tcp [ 4148.428817] Key type .llcrypt unregistered [ 4148.429858] Key type ._llcrypt unregistered [ 4153.742800] Key type ._llcrypt registered [ 4153.743719] Key type .llcrypt registered [ 4154.211237] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 4154.852041] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 22:46:32 (1772682392) [ 4162.364976] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4162.377096] alg: No test for adler32 (adler32-zlib) [ 4163.290313] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 4163.380132] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 4164.960235] Key type lgssc registered [ 4165.472765] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4199.326356] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4200.390753] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4208.170290] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid [ 4216.151316] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid [ 4218.491454] Lustre: Mounted lustre-client [ 4226.879698] LustreError: 188861:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff964089c40000: lov tgt 1000 not cleaned! deathrow=0, lovrc=1 [ 4226.928245] Lustre: Unmounted lustre-client [ 4290.925940] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4292.245523] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4299.893926] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4330.762977] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 22:49:28 (1772682568) [ 4331.343915] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 4331.951965] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 22:49:30 (1772682570) [ 4350.463735] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4351.691774] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4376.396709] Key type lgssc unregistered [ 4376.510501] LNet: 195025:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4376.512652] LNetError: 195025:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4376.522537] LNet: Removed LNI 192.168.201.3@tcp [ 4376.747229] Key type .llcrypt unregistered [ 4376.748427] Key type ._llcrypt unregistered [ 4381.740664] Key type ._llcrypt registered [ 4381.741629] Key type .llcrypt registered [ 4382.188782] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 22:50:20 (1772682620) [ 4415.615804] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4415.622281] alg: No test for adler32 (adler32-zlib) [ 4416.477175] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 4416.566722] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 4418.152137] Key type lgssc registered [ 4418.522629] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4432.432851] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4433.623445] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4450.672817] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4461.708785] Key type lgssc unregistered [ 4461.835292] LNet: 198654:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4461.837174] LNetError: 198654:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4461.847216] LNet: Removed LNI 192.168.201.3@tcp [ 4462.044480] Key type .llcrypt unregistered [ 4462.046153] Key type ._llcrypt unregistered [ 4467.004051] Key type ._llcrypt registered [ 4467.004953] Key type .llcrypt registered [ 4467.454678] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 22:51:45 (1772682705) [ 4468.202684] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4468.249674] alg: No test for adler32 (adler32-zlib) [ 4469.105340] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 4469.185902] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 4470.768148] Key type lgssc registered [ 4471.174087] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4490.724287] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4492.034437] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4498.766793] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4509.174172] Lustre: Mounted lustre-client [ 4514.275879] Lustre: lustre-MDT0000-mdc-ffff9640854ef800: Connection to lustre-MDT0000 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4524.519750] Lustre: lustre-MDT0001-mdc-ffff9640854ef800: Connection to lustre-MDT0001 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4535.776321] Lustre: 199642:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772682758/real 1772682758] req@000000004ba83f1a x1858792542257408/t0(0) o400->MGC192.168.201.103@tcp@192.168.201.103@tcp:26/25 lens 224/224 e 0 to 1 dl 1772682774 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4535.789108] LustreError: MGC192.168.201.103@tcp: Connection to MGS (at 192.168.201.103@tcp) was lost; in progress operations using this service will fail [ 4545.959241] Lustre: Evicted from MGS (at 192.168.201.103@tcp) after server handle changed from 0xcc7f43d3f3c9c10a to 0xcc7f43d3f3c9dbf9 [ 4545.964348] Lustre: MGC192.168.201.103@tcp: Connection restored to 192.168.201.103@tcp (at 192.168.201.103@tcp) [ 4554.011191] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4560.292281] LustreError: lustre-MDT0000-mdc-ffff9640854ef800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 4560.316577] Lustre: lustre-MDT0000-mdc-ffff9640854ef800: Connection restored to 192.168.201.103@tcp (at 192.168.201.103@tcp) [ 4561.737690] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 7 sec [ 4562.954120] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4563.496374] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 4564.137656] LustreError: 203109:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9640854ef800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4564.164115] Lustre: Unmounted lustre-client [ 4582.060499] Key type lgssc unregistered [ 4582.189427] LNet: 203513:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4582.192456] LNetError: 203513:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4582.200424] LNet: Removed LNI 192.168.201.3@tcp [ 4582.465437] Key type .llcrypt unregistered [ 4582.466740] Key type ._llcrypt unregistered [ 4588.651751] Key type ._llcrypt registered [ 4588.653125] Key type .llcrypt registered [ 4589.165849] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 22:53:47 (1772682827) [ 4590.029913] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4590.057626] alg: No test for adler32 (adler32-zlib) [ 4590.934689] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 4591.034066] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 4592.616158] Key type lgssc registered [ 4593.070931] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4612.638243] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4614.101890] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4637.278235] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4638.773943] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4649.999125] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4658.035762] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4688.564753] Key type lgssc unregistered [ 4688.692572] LNet: 208612:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4688.695526] LNetError: 208612:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4688.705909] LNet: Removed LNI 192.168.201.3@tcp [ 4688.966368] Key type .llcrypt unregistered [ 4688.967689] Key type ._llcrypt unregistered [ 4712.025473] Key type ._llcrypt registered [ 4712.027248] Key type .llcrypt registered [ 4712.667262] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 22:55:50 (1772682950) [ 4728.378615] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4728.395050] alg: No test for adler32 (adler32-zlib) [ 4729.286222] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 4729.393266] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 4730.985877] Key type lgssc registered [ 4731.463295] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4766.756431] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4768.395406] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4776.789848] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4800.528477] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 22:57:18 (1772683038) [ 4803.563485] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 22:57:21 (1772683041) [ 4816.834517] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4818.422278] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4825.961169] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4836.341088] Lustre: Mounted lustre-client [ 4839.774079] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [ 4840.739923] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 4841.410794] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 4842.090772] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [ 4846.563563] Lustre: lustre-MDT0000-mdc-ffff964086a87000: Connection to lustre-MDT0000 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4856.802757] LustreError: MGC192.168.201.103@tcp: Connection to MGS (at 192.168.201.103@tcp) was lost; in progress operations using this service will fail [ 4856.811160] Lustre: Evicted from MGS (at 192.168.201.103@tcp) after server handle changed from 0xa61ce1af6f2585fb to 0xa61ce1af6f258871 [ 4856.814710] Lustre: MGC192.168.201.103@tcp: Connection restored to 192.168.201.103@tcp (at 192.168.201.103@tcp) [ 4862.053946] Lustre: lustre-MDT0000-mdc-ffff964086a87000: Connection restored to 192.168.201.103@tcp (at 192.168.201.103@tcp) [ 4863.446876] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4864.057364] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 4866.030651] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [ 4867.042571] Lustre: lustre-MDT0000-mdc-ffff964086a87000: Connection to lustre-MDT0000 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4882.400100] Lustre: 210504:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772683105/real 1772683105] req@00000000d40f79c6 x1858792815869312/t0(0) o400->MGC192.168.201.103@tcp@192.168.201.103@tcp:26/25 lens 224/224 e 0 to 1 dl 1772683121 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4882.407829] LustreError: MGC192.168.201.103@tcp: Connection to MGS (at 192.168.201.103@tcp) was lost; in progress operations using this service will fail [ 4882.422170] Lustre: Evicted from MGS (at 192.168.201.103@tcp) after server handle changed from 0xa61ce1af6f258871 to 0xa61ce1af6f258b81 [ 4882.426336] Lustre: MGC192.168.201.103@tcp: Connection restored to 192.168.201.103@tcp (at 192.168.201.103@tcp) [ 4885.614603] Lustre: lustre-MDT0000-mdc-ffff964086a87000: Connection restored to 192.168.201.103@tcp (at 192.168.201.103@tcp) [ 4887.029969] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4887.677823] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 4889.367285] LustreError: 218739:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff964086a87000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4889.400580] Lustre: Unmounted lustre-client [ 4925.056371] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4926.543794] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4933.590617] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4944.391311] Lustre: Mounted lustre-client [ 4944.834546] LustreError: 220352:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9640875f2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4944.873566] Lustre: Unmounted lustre-client [ 4980.238669] Key type lgssc unregistered [ 4980.381484] LNet: 220757:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4980.384514] LNetError: 220757:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4980.393035] LNet: Removed LNI 192.168.201.3@tcp [ 4980.684421] Key type .llcrypt unregistered [ 4980.686517] Key type ._llcrypt unregistered [ 4986.981733] Key type ._llcrypt registered [ 4986.982603] Key type .llcrypt registered [ 4987.509593] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 23:00:25 (1772683225) [ 4989.044966] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4989.104901] alg: No test for adler32 (adler32-zlib) [ 4989.992603] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 4990.090963] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 4991.688133] Key type lgssc registered [ 4992.142516] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5011.720698] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5013.145200] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5020.233068] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5031.926649] Lustre: Mounted lustre-client [ 5035.027854] LustreError: 223934:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff964086a80000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5035.058404] Lustre: Unmounted lustre-client [ 5069.882452] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5071.132502] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5077.771951] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5088.244026] Lustre: Mounted lustre-client [ 5094.398152] LustreError: 225530:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff96408a671800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5094.428707] Lustre: Unmounted lustre-client [ 5128.092740] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5129.445424] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5136.152100] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5146.614953] Lustre: Mounted lustre-client [ 5146.988904] LustreError: 227141:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff96408c432000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5147.018130] Lustre: Unmounted lustre-client [ 5165.042536] Key type lgssc unregistered [ 5165.164797] LNet: 227544:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5165.168468] LNetError: 227544:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5165.177839] LNet: Removed LNI 192.168.201.3@tcp [ 5165.447915] Key type .llcrypt unregistered [ 5165.448849] Key type ._llcrypt unregistered [ 5171.164180] Key type ._llcrypt registered [ 5171.165378] Key type .llcrypt registered [ 5171.653122] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 23:03:29 (1772683409) [ 5172.793076] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5172.830563] alg: No test for adler32 (adler32-zlib) [ 5173.700419] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 5173.790479] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 5175.384162] Key type lgssc registered [ 5175.837065] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5193.198934] Key type lgssc unregistered [ 5193.339333] LNet: 229859:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5193.343200] LNetError: 229859:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5193.352377] LNet: Removed LNI 192.168.201.3@tcp [ 5193.584482] Key type .llcrypt unregistered [ 5193.585937] Key type ._llcrypt unregistered [ 5203.694340] Key type ._llcrypt registered [ 5203.696682] Key type .llcrypt registered [ 5203.845615] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5203.851542] alg: No test for adler32 (adler32-zlib) [ 5204.741376] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 5204.844880] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 5206.440150] Key type lgssc registered [ 5206.916761] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5238.700983] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5240.188401] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5248.418907] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5266.884883] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [ 5267.541201] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 23:05:05 (1772683505) [ 5279.427892] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5280.768322] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5287.553172] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5296.265313] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5297.464629] Lustre: Mounted lustre-client [ 5302.757321] Lustre: lustre-OST0001-osc-ffff964084e6d000: Connection to lustre-OST0001 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5305.180205] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5315.453167] Lustre: Unmounted lustre-client [ 5339.135445] Key type lgssc unregistered [ 5339.268598] LNet: 236749:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5339.271037] LNetError: 236749:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5339.281802] LNet: Removed LNI 192.168.201.3@tcp [ 5339.554499] Key type .llcrypt unregistered [ 5339.555452] Key type ._llcrypt unregistered [ 5349.852298] Key type ._llcrypt registered [ 5349.853299] Key type .llcrypt registered [ 5350.295580] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 23:06:28 (1772683588) [ 5354.581101] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5354.628104] alg: No test for adler32 (adler32-zlib) [ 5355.494763] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 5355.582586] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 5357.160155] Key type lgssc registered [ 5357.578789] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5375.103680] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5376.322949] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5396.268697] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 23:07:14 (1772683634) [ 5405.525925] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5406.664526] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5412.395123] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5418.966523] Lustre: Mounted lustre-client [ 5421.373570] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 5424.684123] Lustre: Unmounted lustre-client [ 5472.451897] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5473.674472] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5506.171141] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5516.785033] Lustre: Mounted lustre-client [ 5517.189925] LustreError: 243820:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff964086eb8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5517.222109] Lustre: Unmounted lustre-client [ 5534.579905] Key type lgssc unregistered [ 5534.696444] LNet: 244223:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5534.698869] LNetError: 244223:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5534.708335] LNet: Removed LNI 192.168.201.3@tcp [ 5534.968761] Key type .llcrypt unregistered [ 5534.970549] Key type ._llcrypt unregistered [ 5544.672899] Key type ._llcrypt registered [ 5544.674151] Key type .llcrypt registered [ 5544.930349] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5544.935581] alg: No test for adler32 (adler32-zlib) [ 5545.800816] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 5545.897969] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 5547.488115] Key type lgssc registered [ 5547.906655] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5576.785149] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5578.002013] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5585.372677] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5610.653210] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [ 5611.209374] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 23:10:49 (1772683849) [ 5621.018381] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5622.256225] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5628.581479] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5640.174488] Lustre: Mounted lustre-client [ 5641.025060] LustreError: 250263:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff964086ebe000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5641.054160] Lustre: Unmounted lustre-client [ 5666.312341] Key type lgssc unregistered [ 5666.435704] LNet: 250667:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5666.438248] LNetError: 250667:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5666.450130] LNet: Removed LNI 192.168.201.3@tcp [ 5666.684518] Key type .llcrypt unregistered [ 5666.685571] Key type ._llcrypt unregistered [ 5671.932613] Key type ._llcrypt registered [ 5671.933617] Key type .llcrypt registered [ 5672.379510] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [ 5672.916826] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 23:11:51 (1772683911) [ 5675.898324] Key type .llcrypt unregistered [ 5675.899509] Key type ._llcrypt unregistered [ 5678.663129] Key type ._llcrypt registered [ 5678.664152] Key type .llcrypt registered [ 5678.838470] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5678.843585] alg: No test for adler32 (adler32-zlib) [ 5679.710617] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 5679.796848] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 5681.384128] Key type lgssc registered [ 5681.740829] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5698.301337] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5703.846920] Lustre: Mounted lustre-client [ 5704.334802] LustreError: 253681:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9640854e9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5704.363652] Lustre: Unmounted lustre-client [ 5713.954877] Key type lgssc unregistered [ 5714.074626] LNet: 254084:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5714.077053] LNetError: 254084:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5714.085848] LNet: Removed LNI 192.168.201.3@tcp [ 5714.333944] Key type .llcrypt unregistered [ 5714.335571] Key type ._llcrypt unregistered [ 5719.278459] Key type ._llcrypt registered [ 5719.279276] Key type .llcrypt registered [ 5719.686923] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 23:12:37 (1772683957) [ 5720.389764] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5720.418543] alg: No test for adler32 (adler32-zlib) [ 5721.269199] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 5721.340150] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 5722.920132] Key type lgssc registered [ 5723.248278] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5781.413191] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5790.190190] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5791.295253] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5796.847653] Lustre: Mounted lustre-client [ 5797.259655] LustreError: 257128:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff964087564800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5797.286669] Lustre: Unmounted lustre-client [ 5814.435635] Key type lgssc unregistered [ 5814.548523] LNet: 257531:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5814.550767] LNetError: 257531:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5814.561579] LNet: Removed LNI 192.168.201.3@tcp [ 5814.783901] Key type .llcrypt unregistered [ 5814.784859] Key type ._llcrypt unregistered [ 5820.085778] Key type ._llcrypt registered [ 5820.087401] Key type .llcrypt registered [ 5820.584431] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 23:14:18 (1772684058) [ 5821.357750] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5821.408480] alg: No test for adler32 (adler32-zlib) [ 5822.262804] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 5822.343205] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 5823.928151] Key type lgssc registered [ 5824.269724] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5841.012635] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5842.219530] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5848.352611] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5863.918708] Lustre: Mounted lustre-client [ 5869.027179] Lustre: lustre-MDT0000-mdc-ffff96408a863000: Connection to lustre-MDT0000 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5871.643654] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5871.654855] LustreError: 260592:0:(file.c:6162:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [ 5871.760046] LustreError: 260611:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff96408a863000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5871.761367] LustreError: MGC192.168.201.103@tcp: Connection to MGS (at 192.168.201.103@tcp) was lost; in progress operations using this service will fail [ 5871.786119] Lustre: Unmounted lustre-client [ 5882.417401] Key type lgssc unregistered [ 5882.531383] LNet: 261006:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5882.533385] LNetError: 261006:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5882.542402] LNet: Removed LNI 192.168.201.3@tcp [ 5882.750168] Key type .llcrypt unregistered [ 5882.751056] Key type ._llcrypt unregistered [ 5887.447399] Key type ._llcrypt registered [ 5887.448366] Key type .llcrypt registered [ 5887.849721] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 23:15:26 (1772684126) [ 5888.591662] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5888.601420] alg: No test for adler32 (adler32-zlib) [ 5889.444467] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 5889.515443] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 5891.096170] Key type lgssc registered [ 5891.441958] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5907.977664] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5909.226491] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5915.358770] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5926.897440] Lustre: Mounted lustre-client [ 5932.004154] Lustre: lustre-MDT0001-mdc-ffff96408a677000: Connection to lustre-MDT0001 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5934.645111] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 5935.015444] LustreError: 264097:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff96408a677000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5935.044251] Lustre: Unmounted lustre-client [ 5945.740333] Key type lgssc unregistered [ 5945.856552] LNet: 264492:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5945.858595] LNetError: 264492:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5945.867671] LNet: Removed LNI 192.168.201.3@tcp [ 5946.088932] Key type .llcrypt unregistered [ 5946.090267] Key type ._llcrypt unregistered [ 5951.581762] Key type ._llcrypt registered [ 5951.582867] Key type .llcrypt registered [ 5952.060492] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 23:16:30 (1772684190) [ 5957.913706] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5957.941196] alg: No test for adler32 (adler32-zlib) [ 5958.801991] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 5958.879972] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 5960.456162] Key type lgssc registered [ 5960.898676] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5997.798624] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6004.187467] Lustre: Mounted lustre-client [ 6021.091698] Lustre: lustre-MDT0001-mdc-ffff96408c45e000: Connection to lustre-MDT0001 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6032.731416] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6042.999640] Lustre: Unmounted lustre-client [ 6060.081108] Key type lgssc unregistered [ 6060.196594] LNet: 270230:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6060.200079] LNetError: 270230:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6060.209710] LNet: Removed LNI 192.168.201.3@tcp [ 6060.453062] Key type .llcrypt unregistered [ 6060.455365] Key type ._llcrypt unregistered [ 6065.425504] Key type ._llcrypt registered [ 6065.426450] Key type .llcrypt registered [ 6065.869714] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 23:18:24 (1772684304) [ 6066.425436] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [ 6066.987766] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 23:18:25 (1772684305) [ 6067.525771] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [ 6068.099268] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 23:18:26 (1772684306) [ 6068.642322] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [ 6069.210056] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 23:18:27 (1772684307) [ 6069.760331] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [ 6070.314171] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 23:18:28 (1772684308) [ 6070.839080] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [ 6071.379523] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 23:18:29 (1772684309) [ 6074.406408] Key type .llcrypt unregistered [ 6074.408560] Key type ._llcrypt unregistered [ 6076.847366] Key type ._llcrypt registered [ 6076.848588] Key type .llcrypt registered [ 6077.053967] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6077.059275] alg: No test for adler32 (adler32-zlib) [ 6077.940413] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 6078.032589] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 6079.624187] Key type lgssc registered [ 6080.088867] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6104.997219] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6106.171895] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6113.526276] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6114.705454] Lustre: Mounted lustre-client [ 6115.181726] LustreError: 275815:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff964087b96000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6115.216697] Lustre: Unmounted lustre-client [ 6138.331136] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 23:19:36 (1772684376) [ 6148.559144] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6149.777407] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6166.998864] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6167.174033] Lustre: Mounted lustre-client [ 6167.523479] LustreError: 277924:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff964086ebf800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6167.546872] Lustre: Unmounted lustre-client [ 6205.114437] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 23:20:43 (1772684443) [ 6227.552386] LNet: 1 local NIs in recovery (showing 1): 192.168.201.3@tcp [ 6791.834382] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6793.069955] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6810.178945] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6851.227833] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 23:31:29 (1772685089) [ 6855.026895] Key type lgssc unregistered [ 6855.135608] LNet: 283457:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6855.139237] LNetError: 283457:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6855.147724] LNet: Removed LNI 192.168.201.3@tcp [ 6855.380380] Key type .llcrypt unregistered [ 6855.381549] Key type ._llcrypt unregistered [ 6936.909261] Key type ._llcrypt registered [ 6936.920878] Key type .llcrypt registered [ 6937.115629] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6937.120819] alg: No test for adler32 (adler32-zlib) [ 6937.970856] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 6938.048624] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 6939.632158] Key type lgssc registered [ 6940.037021] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6970.440732] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6971.601599] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6980.487858] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6999.753990] Key type lgssc unregistered [ 6999.875614] LNet: 287047:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6999.877944] LNetError: 287047:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6999.890437] LNet: Removed LNI 192.168.201.3@tcp [ 7000.115382] Key type .llcrypt unregistered [ 7000.116344] Key type ._llcrypt unregistered [ 7021.995583] Key type ._llcrypt registered [ 7021.996515] Key type .llcrypt registered [ 7022.413251] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 23:34:20 (1772685260) [ 7028.784134] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7028.808946] alg: No test for adler32 (adler32-zlib) [ 7029.668306] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 7029.759500] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7031.344183] Key type lgssc registered [ 7031.693594] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7047.999650] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 23:34:46 (1772685286) [ 7051.727213] Key type lgssc unregistered [ 7051.839666] LNet: 290590:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7051.842108] LNetError: 290590:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7051.849965] LNet: Removed LNI 192.168.201.3@tcp [ 7052.093699] Key type .llcrypt unregistered [ 7052.094643] Key type ._llcrypt unregistered [ 7055.747280] Key type ._llcrypt registered [ 7055.748318] Key type .llcrypt registered [ 7055.879274] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7055.883909] alg: No test for adler32 (adler32-zlib) [ 7056.744151] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 7056.830663] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7058.416102] Key type lgssc registered [ 7058.771898] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7078.843581] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7080.020819] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7087.072696] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7166.118170] LNet: Added LNI 192.168.201.3@tcp50 [8/256/0/180] [ 7166.853571] LNet: Removed LNI 192.168.201.3@tcp [ 7168.236634] Lustre: 294855:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff96408a867000: config has no valid NIDs, force dynamic NIDs [ 7169.281135] Lustre: Mounted lustre-client [ 7169.300489] LustreError: 294870:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff96408a867000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7169.326102] Lustre: Unmounted lustre-client [ 7170.146639] Key type lgssc unregistered [ 7170.284386] LNet: 295121:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7170.286988] LNetError: 295121:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7170.298307] LNet: Removed LNI 192.168.201.3@tcp50 [ 7170.480958] Key type .llcrypt unregistered [ 7170.482113] Key type ._llcrypt unregistered [ 7180.135565] Key type ._llcrypt registered [ 7180.136379] Key type .llcrypt registered [ 7180.306407] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7180.310936] alg: No test for adler32 (adler32-zlib) [ 7181.172076] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 7181.251820] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7182.832117] Key type lgssc registered [ 7183.167891] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7211.010497] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7212.165438] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7214.406466] LNet: Added LNI 192.168.201.3@tcp50 [8/256/0/180] [ 7214.661856] LNet: Removed LNI 192.168.201.3@tcp [ 7215.723928] Lustre: 297743:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff9640892f6800: config has no valid NIDs, force dynamic NIDs [ 7216.765961] Lustre: Mounted lustre-client [ 7216.877156] LustreError: 297778:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9640892f6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7216.901406] Lustre: Unmounted lustre-client [ 7235.313951] Key type lgssc unregistered [ 7235.446454] LNet: 298619:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7235.448826] LNetError: 298619:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7235.455627] LNet: Removed LNI 192.168.201.3@tcp50 [ 7235.626939] Key type .llcrypt unregistered [ 7235.627870] Key type ._llcrypt unregistered [ 7239.019399] Key type ._llcrypt registered [ 7239.020305] Key type .llcrypt registered [ 7239.427673] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 23:37:57 (1772685477) [ 7240.075494] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7240.125362] alg: No test for adler32 (adler32-zlib) [ 7240.968756] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 7241.042979] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7242.616099] Key type lgssc registered [ 7242.941077] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7258.855094] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7260.015552] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7265.588149] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7266.739227] Lustre: Mounted lustre-client [ 7286.307571] Lustre: Mounted fs15246-client [ 7290.607919] LustreError: 302744:0:(lov_obd.c:783:lov_cleanup()) fs15246-clilov-ffff964085842000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7290.626188] Lustre: Unmounted fs15246-client [ 7303.365938] LustreError: 302960:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff96408af50800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7303.392166] Lustre: Unmounted lustre-client [ 7320.881041] Key type lgssc unregistered [ 7320.997534] LNet: 303363:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7320.999908] LNetError: 303363:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7321.009707] LNet: Removed LNI 192.168.201.3@tcp [ 7321.234376] Key type .llcrypt unregistered [ 7321.235283] Key type ._llcrypt unregistered [ 7325.093991] Key type ._llcrypt registered [ 7325.095019] Key type .llcrypt registered [ 7325.714905] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 23:39:23 (1772685563) [ 7326.524997] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7326.543695] alg: No test for adler32 (adler32-zlib) [ 7327.483833] Lustre: Lustre: Build Version: 2.17.50_193_g407ab0b [ 7327.583800] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7329.184137] Key type lgssc registered [ 7329.730141] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7376.641576] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7377.866484] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7386.051477] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7435.980675] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 7269 sec ========== 23:41:14 (1772685674) [ 7436.513773] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 23:41:14 (1772685674) === [ 7437.735210] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 23:41:15 (1772685675) === [ 7449.467640] Key type lgssc unregistered [ 7449.587421] LNet: 312506:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7449.589757] LNetError: 312506:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7449.599815] LNet: Removed LNI 192.168.201.3@tcp [ 7449.854485] Key type .llcrypt unregistered [ 7449.855521] Key type ._llcrypt unregistered