[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.17.0-10.fc44 06/10/2025 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 478319058 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 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 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: 2895288K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524584K 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.001014] APIC: Switch to symmetric I/O mode setup [ 0.003277] x2apic enabled [ 0.004010] Switched APIC routing to physical x2apic. [ 0.005018] kvm-guest: setup PV IPIs [ 0.008763] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.009000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.009024] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.010017] pid_max: default: 32768 minimum: 301 [ 0.011117] LSM: Security Framework initializing [ 0.012084] Yama: becoming mindful. [ 0.013043] SELinux: Initializing. [ 0.015103] *** VALIDATE selinux *** [ 0.024187] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.029140] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.030166] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031156] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.032139] *** VALIDATE tmpfs *** [ 0.034486] *** VALIDATE proc *** [ 0.035248] *** VALIDATE cgroup *** [ 0.036011] *** VALIDATE cgroup2 *** [ 0.038306] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.039171] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.040012] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.041030] Spectre V2 : User space: Vulnerable [ 0.042013] Speculative Store Bypass: Vulnerable [ 0.045643] debug: unmapping init [mem 0xffffffff90459000-0xffffffff90460fff] [ 0.047217] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.048770] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.049026] ... version: 2 [ 0.050013] ... bit width: 48 [ 0.051013] ... generic registers: 4 [ 0.052013] ... value mask: 0000ffffffffffff [ 0.053015] ... max period: 00007fffffffffff [ 0.054018] ... fixed-purpose events: 3 [ 0.055014] ... event mask: 000000070000000f [ 0.056312] rcu: Hierarchical SRCU implementation. [ 0.058467] smp: Bringing up secondary CPUs ... [ 0.059611] x86: Booting SMP configuration: [ 0.060031] .... node #0, CPUs: #1 #2 #3 [ 0.064026] smp: Brought up 1 node, 4 CPUs [ 0.066017] smpboot: Max logical packages: 1 [ 0.067012] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.128021] node 0 deferred pages initialised in 59ms [ 0.131036] devtmpfs: initialized [ 0.132318] x86/mm: Memory block size: 128MB [ 0.135955] gcov: version magic: 0x41383552 [ 0.139392] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.140074] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.142357] pinctrl core: initialized pinctrl subsystem [ 0.145233] [ 0.145867] ************************************************************* [ 0.148013] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.150012] ** ** [ 0.152013] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.154014] ** ** [ 0.156014] ** This means that this kernel is built to expose internal ** [ 0.158015] ** IOMMU data structures, which may compromise security on ** [ 0.161014] ** your system. ** [ 0.163014] ** ** [ 0.166017] ** If you see this message and you are not debugging the ** [ 0.168015] ** kernel, report this immediately to your vendor! ** [ 0.170017] ** ** [ 0.172012] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.175016] ************************************************************* [ 0.177774] NET: Registered protocol family 16 [ 0.180496] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.183057] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.185066] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.190022] cpuidle: using governor menu [ 0.191847] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.194515] PCI: Using configuration type 1 for base access [ 0.196131] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.204125] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.206020] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.210106] cryptd: max_cpu_qlen set to 1000 [ 0.213246] ACPI: Added _OSI(Module Device) [ 0.215017] ACPI: Added _OSI(Processor Device) [ 0.216011] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.218016] ACPI: Added _OSI(Processor Aggregator Device) [ 0.223158] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.229092] ACPI: Interpreter enabled [ 0.230059] ACPI: PM: (supports S0 S3 S4 S5) [ 0.232015] ACPI: Using IOAPIC for interrupt routing [ 0.233100] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.236387] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.248406] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.251055] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.254026] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.258093] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.263587] acpiphp: Slot [2] registered [ 0.265164] acpiphp: Slot [5] registered [ 0.266220] acpiphp: Slot [6] registered [ 0.268206] acpiphp: Slot [3] registered [ 0.270205] acpiphp: Slot [4] registered [ 0.273243] acpiphp: Slot [7] registered [ 0.276251] acpiphp: Slot [8] registered [ 0.280343] acpiphp: Slot [9] registered [ 0.282141] acpiphp: Slot [10] registered [ 0.283092] acpiphp: Slot [11] registered [ 0.284080] acpiphp: Slot [12] registered [ 0.286088] acpiphp: Slot [13] registered [ 0.287094] acpiphp: Slot [14] registered [ 0.288084] acpiphp: Slot [15] registered [ 0.289118] acpiphp: Slot [16] registered [ 0.290105] acpiphp: Slot [17] registered [ 0.291080] acpiphp: Slot [18] registered [ 0.293097] acpiphp: Slot [19] registered [ 0.294538] acpiphp: Slot [20] registered [ 0.295082] acpiphp: Slot [21] registered [ 0.297114] acpiphp: Slot [22] registered [ 0.298089] acpiphp: Slot [23] registered [ 0.299094] acpiphp: Slot [24] registered [ 0.300109] acpiphp: Slot [25] registered [ 0.301077] acpiphp: Slot [26] registered [ 0.302109] acpiphp: Slot [27] registered [ 0.304133] acpiphp: Slot [28] registered [ 0.306131] acpiphp: Slot [29] registered [ 0.307144] acpiphp: Slot [30] registered [ 0.309128] acpiphp: Slot [31] registered [ 0.311073] PCI host bridge to bus 0000:00 [ 0.313021] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.314015] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.316022] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.318025] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.322027] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.325025] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.327221] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.330163] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.334063] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.341014] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.344638] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.346018] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.348017] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.350014] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.351526] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.353663] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.355038] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.357756] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.361689] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.369011] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.373014] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.378305] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.389019] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.398019] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.412015] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.424595] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.431008] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.440015] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.459033] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.469692] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.472430] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.474312] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.476332] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.478213] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.483722] iommu: Default domain type: Passthrough [ 0.486622] SCSI subsystem initialized [ 0.488120] ACPI: bus type USB registered [ 0.490141] usbcore: registered new interface driver usbfs [ 0.492095] usbcore: registered new interface driver hub [ 0.495100] usbcore: registered new device driver usb [ 0.498213] pps_core: LinuxPPS API ver. 1 registered [ 0.500013] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.503065] PTP clock support registered [ 0.506101] EDAC MC: Ver: 3.0.0 [ 0.508444] PCI: Using ACPI for IRQ routing [ 0.510738] NetLabel: Initializing [ 0.511014] NetLabel: domain hash size = 128 [ 0.513011] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.514078] NetLabel: unlabeled traffic allowed by default [ 0.516166] vgaarb: loaded [ 0.517262] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.519013] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.528390] clocksource: Switched to clocksource kvm-clock [ 0.654339] VFS: Disk quotas dquot_6.6.0 [ 0.655820] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.658512] *** VALIDATE ramfs *** [ 0.659723] *** VALIDATE hugetlbfs *** [ 0.660835] pnp: PnP ACPI init [ 0.663554] pnp: PnP ACPI: found 6 devices [ 0.685412] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.689444] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.692110] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.694686] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.697191] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.699911] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.702745] NET: Registered protocol family 2 [ 0.705330] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.710731] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.714978] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.720792] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.724652] TCP: Hash tables configured (established 65536 bind 65536) [ 0.727900] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.731518] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.734599] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.738018] NET: Registered protocol family 1 [ 0.741578] RPC: Registered named UNIX socket transport module. [ 0.743408] RPC: Registered udp transport module. [ 0.744844] RPC: Registered tcp transport module. [ 0.746553] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.749077] NET: Registered protocol family 44 [ 0.751100] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.753575] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.756212] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.759050] PCI: CLS 0 bytes, default 64 [ 0.760991] Unpacking initramfs... [ 2.220283] debug: unmapping init [mem 0xffff99493cc64000-0xffff99493ffcffff] [ 2.224875] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.227385] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.230705] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 3.862452] Initialise system trusted keyrings [ 3.869420] Key type blacklist registered [ 3.881896] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 3.903726] zbud: loaded [ 3.912841] *** VALIDATE nfs *** [ 3.918916] *** VALIDATE nfs4 *** [ 3.928654] pstore: using deflate compression [ 3.954643] Platform Keyring initialized [ 4.426861] NET: Registered protocol family 38 [ 4.428842] Key type asymmetric registered [ 4.433211] Asymmetric key parser 'x509' registered [ 4.435138] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 4.449244] io scheduler mq-deadline registered [ 4.451178] io scheduler kyber registered [ 4.458561] io scheduler bfq registered [ 4.465217] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 4.469156] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 4.472309] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 4.475270] ACPI: Power Button [PWRF] [ 4.491295] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 4.510413] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 4.530792] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 4.573296] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 4.621047] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 4.646071] Non-volatile memory driver v1.3 [ 4.650320] Linux agpgart interface v0.103 [ 4.711484] virtio_blk virtio1: [vda] 146024 512-byte logical blocks (74.8 MB/71.3 MiB) [ 4.716588] vda: detected capacity change from 0 to 74764288 [ 4.754490] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 4.761107] vdb: detected capacity change from 0 to 1073741824 [ 4.788484] libphy: Fixed MDIO Bus: probed [ 4.807615] usbcore: registered new interface driver usbserial_generic [ 4.813132] usbserial: USB Serial support registered for generic [ 4.815739] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 4.829351] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 4.838849] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 4.844335] mousedev: PS/2 mouse device common for all mice [ 4.851058] rtc_cmos 00:05: RTC can wake from S4 [ 4.857816] rtc_cmos 00:05: registered as rtc0 [ 4.860476] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 4.866051] intel_pstate: CPU model not supported [ 4.872326] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 4.875640] hid: raw HID events driver (C) Jiri Kosina [ 4.891026] usbcore: registered new interface driver usbhid [ 4.895862] usbhid: USB HID core driver [ 4.897379] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 4.901060] drop_monitor: Initializing network drop monitor service [ 4.901670] Initializing XFRM netlink socket [ 4.908979] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 4.913476] NET: Registered protocol family 10 [ 4.936945] Segment Routing with IPv6 [ 4.940095] NET: Registered protocol family 17 [ 4.952053] mpls_gso: MPLS GSO support [ 4.965667] RAS: Correctable Errors collector initialized. [ 4.968130] AVX version of gcm_enc/dec engaged. [ 4.969865] AES CTR mode by8 optimization enabled [ 5.114478] sched_clock: Marking stable (5114350156, 0)->(6055401985, -941051829) [ 5.120373] registered taskstats version 1 [ 5.122736] Loading compiled-in X.509 certificates [ 5.124952] zswap: loaded using pool lzo/zbud [ 5.153983] Key type big_key registered [ 5.167419] Key type encrypted registered [ 5.168572] ima: No TPM chip found, activating TPM-bypass! [ 5.171636] ima: Allocated hash algorithm: sha1 [ 5.173713] ima: No architecture policies found [ 5.175750] evm: Initialising EVM extended attributes: [ 5.177866] evm: security.selinux [ 5.178887] evm: security.ima [ 5.179847] evm: security.capability [ 5.180903] evm: HMAC attrs: 0x1 [ 5.183280] rtc_cmos 00:05: setting system clock to 2026-09-02 00:40:01 UTC (1788309601) [ 5.190889] debug: unmapping init [mem 0xffffffff91403000-0xffffffff915fffff] [ 5.193948] debug: unmapping init [mem 0xffffffff90182000-0xffffffff90458fff] [ 5.204526] Write protecting the kernel read-only data: 28672k [ 5.210381] debug: unmapping init [mem 0xffffffff8e803000-0xffffffff8e9fffff] [ 5.214859] debug: unmapping init [mem 0xffffffff8f114000-0xffffffff8f1fffff] [ 5.284271] 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) [ 5.301678] systemd[1]: Detected virtualization kvm. [ 5.304375] systemd[1]: Detected architecture x86-64. [ 5.307275] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 5.344781] systemd[1]: No hostname configured. [ 5.346466] systemd[1]: Set hostname to . [ 5.349736] random: systemd: uninitialized urandom read (16 bytes read) [ 5.354427] systemd[1]: Initializing machine ID from random generator. [ 5.472287] random: ln: uninitialized urandom read (6 bytes read) [ 5.623178] random: systemd: uninitialized urandom read (16 bytes read) [ 5.627614] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 5.637925] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 5.647928] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Slices. [ OK ] Listening on Journal Socket. Starting Apply Kernel Variables... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Swap. Starting Setup Virtual Console... [ OK ] Started Memstrack Anylazing Service. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Reached target Sockets. [ OK ] Started Apply Kernel Variables. [ 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. 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... [ 7.120259] device-mapper: uevent: version 1.0.3 [ 7.123614] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK [ 8.796709] random: fast init done ] Started Hardware RNG Entropy Gatherer Daemon. [ 8.927983] virtio_net virtio0 ens2: renamed from eth0 [ 8.963144] scsi host0: ata_piix [ 9.022583] scsi host1: ata_piix [ 9.027286] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 9.034609] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 13.689982] random: crng init done [ 13.693299] random: 7 urandom warning(s) missed due to ratelimiting [ 14.695934] dracut-initqueue[582]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 16.959302] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Timers. [ OK ] Stopped target Initrd Default Target. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Swap. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 19.396446] printk: systemd: 26 output lines suppressed due to ratelimiting [ 20.012471] SELinux: Disabled at runtime. [ 20.106978] 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.135507] systemd[1]: Detected virtualization kvm. [ 20.140443] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 21.847702] systemd[1]: initrd-switch-root.service: Succeeded. [ 21.854685] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 21.876256] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 21.884334] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 21.898021] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 21.924423] systemd[1]: Starting Journal Service... Starting Journal Service... [ 21.937878] systemd[1]: Reached target rpc_pipefs.target. [ OK ] Reached target rpc_pipefs.target. Mounting Huge Pages File System... [ OK ] Started Forward Password Requests to Wall Directory Watch. Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Created slice system-getty.slice. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ 22.076135] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Created slice system-sshd\x2dkeygen.slice. Starting Remount Root and Kernel File Systems... Mounting Kernel Debug File System... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Listening on udev Control Socket. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... Mounting POSIX Message Queue File System... [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Stopped target Initrd File Systems. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on Process Core Dump Socket. Starting Apply Kernel Variables... [ OK ] Started Journal Service. [ OK ] Mounted Huge Pages 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 Kernel Debug File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Apply Kernel Variables. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... Starting udev Kernel Device Manager... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 23.126656] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 24.221093] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 24.268098] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 24.736474] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 24.798405] EDAC sbridge: Ver: 1.1.2 [ 27.551975] Key type dns_resolver registered [ 28.303142] NFS: Registering the id_resolver key type [ 28.309341] Key type id_resolver registered [ 28.313045] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started D-Bus System Message Bus. [ OK ] Started irqbalance daemon. Starting Restore /run/initramfs on shutdown... Starting Network Manager... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Login Service... [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Network Manager Wait Online... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting System Logging Service... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg445-client login: [ 81.589572] hrtimer: interrupt took 11564428 ns [ 91.965148] libcfs: loading out-of-tree module taints kernel. [ 92.076522] Key type ._llcrypt registered [ 92.083800] Key type .llcrypt registered [ 92.582598] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 92.599281] alg: No test for adler32 (adler32-zlib) [ 94.191927] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [ 94.979174] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 96.695332] Key type lgssc registered [ 98.234177] Lustre: Echo OBD driver; http://www.lustre.org/ [ 256.165313] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 261.897557] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 274.607213] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing check_logdir /tmp/testlogs/ [ 280.554716] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing yml_node [ 282.080205] Lustre: lustre-OST0000-osc-ffff994982d6e800: disconnect after 23s idle [ 285.132281] Lustre: DEBUG MARKER: Client: 2.17.57.45 [ 287.684627] Lustre: DEBUG MARKER: MDS: 2.17.57.45 [ 289.975384] Lustre: DEBUG MARKER: OSS: 2.17.57.45 [ 291.618654] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Tue Sep 1 20:44:46 EDT 2026 [ 309.719446] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 311.238767] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 313.995281] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 324.324890] Lustre: Unmounted lustre-client [ 432.552520] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 438.405487] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 460.845324] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 504.837954] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 20:48:19 (1788310099) [ 506.884745] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 508.407638] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 20:48:23 (1788310103) [ 535.612729] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 540.939531] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 559.350525] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 565.288519] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 570.310153] Lustre: Unmounted lustre-client [ 596.715953] Key type lgssc unregistered [ 596.903584] LNet: 15031:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 596.910573] LNetError: 15031:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 596.929309] LNet: Removed LNI 192.168.204.45@tcp [ 597.534644] Key type .llcrypt unregistered [ 597.539246] Key type ._llcrypt unregistered [ 609.240828] Key type ._llcrypt registered [ 609.244147] Key type .llcrypt registered [ 610.226591] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 20:50:05 (1788310205) [ 611.332797] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 611.354901] alg: No test for adler32 (adler32-zlib) [ 612.411817] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [ 612.631068] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 614.280311] Key type lgssc registered [ 615.348972] Lustre: Echo OBD driver; http://www.lustre.org/ [ 681.851872] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 767.182609] Lustre: Unmounted lustre-client [ 803.009505] Key type lgssc unregistered [ 803.261745] LNet: 18837:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 803.272200] LNetError: 18837:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 803.285892] LNet: Removed LNI 192.168.204.45@tcp [ 803.979813] Key type .llcrypt unregistered [ 803.982820] Key type ._llcrypt unregistered [ 812.705598] Key type ._llcrypt registered [ 812.708062] Key type .llcrypt registered [ 814.067157] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 20:53:28 (1788310408) [ 815.972936] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 816.011055] alg: No test for adler32 (adler32-zlib) [ 817.058826] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [ 817.293076] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 818.959215] Key type lgssc registered [ 820.107421] Lustre: Echo OBD driver; http://www.lustre.org/ [ 863.752526] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 868.229211] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 885.110442] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 887.700719] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 898.006253] Lustre: Unmounted lustre-client [ 924.047748] Key type lgssc unregistered [ 924.418953] LNet: 22439:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 924.426470] LNetError: 22439:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 924.447724] LNet: Removed LNI 192.168.204.45@tcp [ 925.092122] Key type .llcrypt unregistered [ 925.097705] Key type ._llcrypt unregistered [ 934.282342] Key type ._llcrypt registered [ 934.285228] Key type .llcrypt registered [ 935.629874] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 20:55:30 (1788310530) [ 937.455057] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 937.501787] alg: No test for adler32 (adler32-zlib) [ 938.593301] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [ 938.795122] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 940.471277] Key type lgssc registered [ 941.569051] Lustre: Echo OBD driver; http://www.lustre.org/ [ 986.543668] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 991.200325] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1010.460362] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1013.000574] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 1018.387786] Lustre: Unmounted lustre-client [ 1043.857263] Key type lgssc unregistered [ 1044.126543] LNet: 25628:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1044.134918] LNetError: 25628:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1044.152925] LNet: Removed LNI 192.168.204.45@tcp [ 1044.824439] Key type .llcrypt unregistered [ 1044.826450] Key type ._llcrypt unregistered [ 1058.548340] Key type ._llcrypt registered [ 1058.552084] Key type .llcrypt registered [ 1059.966371] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 20:57:34 (1788310654) [ 1062.107649] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1062.140646] alg: No test for adler32 (adler32-zlib) [ 1063.227229] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [ 1063.467933] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 1065.169110] Key type lgssc registered [ 1066.595218] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1117.162451] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1123.226576] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1143.462468] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1145.041666] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 1155.570598] Lustre: lustre-MDT0000-mdc-ffff99498837e000: Connection to lustre-MDT0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1165.803913] Lustre: lustre-MDT0001-mdc-ffff99498837e000: Connection to lustre-MDT0001 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1170.139938] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1175.967202] Lustre: 26587:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788310756/real 1788310756] req@0000000085bf8587 x1875179637577728/t0(0) o400->MGC192.168.204.145@tcp@192.168.204.145@tcp:26/25 lens 224/224 e 0 to 1 dl 1788310772 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1175.998583] LustreError: MGC192.168.204.145@tcp: Connection to MGS (at 192.168.204.145@tcp) was lost; in progress operations using this service will fail [ 1180.435429] Lustre: Unmounted lustre-client [ 1198.129566] Key type lgssc unregistered [ 1198.359387] LNet: 29287:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1198.369091] LNetError: 29287:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1198.392544] LNet: Removed LNI 192.168.204.45@tcp [ 1199.056322] Key type .llcrypt unregistered [ 1199.059364] Key type ._llcrypt unregistered [ 1212.378672] Key type ._llcrypt registered [ 1212.381201] Key type .llcrypt registered [ 1213.509586] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 21:00:08 (1788310808) [ 1215.610730] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1215.630180] alg: No test for adler32 (adler32-zlib) [ 1216.694309] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [ 1216.920856] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 1218.591237] Key type lgssc registered [ 1219.813894] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1267.966917] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1273.098504] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1293.946423] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1295.756034] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 1300.982322] Lustre: lustre-OST0000-osc-ffff994985a8c000: Connection to lustre-OST0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1307.613494] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1317.900904] Lustre: Unmounted lustre-client [ 1343.855918] Key type lgssc unregistered [ 1344.144900] LNet: 32916:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1344.153501] LNetError: 32916:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1344.171397] LNet: Removed LNI 192.168.204.45@tcp [ 1344.793383] Key type .llcrypt unregistered [ 1344.807192] Key type ._llcrypt unregistered [ 1360.815775] Key type ._llcrypt registered [ 1360.817795] Key type .llcrypt registered [ 1362.060068] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 21:02:36 (1788310956) [ 1364.274866] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1364.294725] alg: No test for adler32 (adler32-zlib) [ 1365.389607] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [ 1365.659234] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 1367.415402] Key type lgssc registered [ 1368.589425] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1416.924177] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1424.050474] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1445.049343] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1447.749231] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 1454.670294] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1457.976662] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1469.560206] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-09-0121h04m24s [ 1472.009249] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1478.627486] Lustre: lustre-MDT0000-mdc-ffff99498837a000: Connection to lustre-MDT0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1482.668665] LustreError: 36706:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff99498837a000: can't stat MDS #0: rc = -19 [ 1484.205563] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1491.947601] LustreError: MGC192.168.204.145@tcp: Connection to MGS (at 192.168.204.145@tcp) was lost; in progress operations using this service will fail [ 1491.955089] LustreError: lustre-MDT0000-mdc-ffff99498837a000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1491.979667] Lustre: Evicted from MGS (at 192.168.204.145@tcp) after server handle changed from 0x6d781aa8270d3ef to 0x6d781aa8270d6a4 [ 1491.989715] Lustre: lustre-MDT0000-mdc-ffff99498837a000: Connection restored to 192.168.204.145@tcp (at 192.168.204.145@tcp) [ 1492.005944] Lustre: Skipped 1 previous similar message [ 1499.805192] Lustre: DEBUG MARKER: Wait for df (36706) ... [ 1501.275931] Lustre: DEBUG MARKER: done [ 1503.544138] Lustre: Unmounted lustre-client [ 1529.116851] Key type lgssc unregistered [ 1529.420701] LNet: 37726:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1529.431332] LNetError: 37726:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1529.453841] LNet: Removed LNI 192.168.204.45@tcp [ 1530.143808] Key type .llcrypt unregistered [ 1530.147093] Key type ._llcrypt unregistered [ 1557.877705] Key type ._llcrypt registered [ 1557.880680] Key type .llcrypt registered [ 1559.383834] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 21:05:54 (1788311154) [ 1561.816200] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1561.905054] alg: No test for adler32 (adler32-zlib) [ 1563.100411] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [ 1563.341821] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 1565.023286] Key type lgssc registered [ 1566.397476] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1618.604514] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1624.723340] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1645.559571] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1647.375908] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 1652.573129] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-09-0121h07m27s [ 1654.202526] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1661.316614] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1664.687408] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1684.962418] Lustre: 41878:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788311261/real 1788311261] req@00000000e42e561c x1875180161866368/t0(0) o101->lustre-MDT0000-mdc-ffff994987828000@192.168.204.145@tcp:12/10 lens 576/1152 e 0 to 1 dl 1788311281 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1685.003403] Lustre: lustre-MDT0000-mdc-ffff994987828000: Connection to lustre-MDT0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1685.052924] Lustre: lustre-MDT0000-mdc-ffff994987828000: Connection restored to 192.168.204.145@tcp (at 192.168.204.145@tcp) [ 1706.430784] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1709.412541] Lustre: DEBUG MARKER: done [ 1712.940581] Lustre: Unmounted lustre-client [ 1748.548513] Key type lgssc unregistered [ 1748.868987] LNet: 42765:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1748.875472] LNetError: 42765:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1748.902557] LNet: Removed LNI 192.168.204.45@tcp [ 1749.740548] Key type .llcrypt unregistered [ 1749.742213] Key type ._llcrypt unregistered [ 1781.899801] Key type ._llcrypt registered [ 1781.906736] Key type .llcrypt registered [ 1783.158344] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 21:09:38 (1788311378) [ 1784.692766] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1786.462674] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 21:09:41 (1788311381) [ 1789.985309] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1790.055455] alg: No test for adler32 (adler32-zlib) [ 1791.236687] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [ 1791.510742] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 1793.207972] Key type lgssc registered [ 1794.552385] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1823.647455] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 21:10:18 (1788311418) [ 1859.427260] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1865.422667] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1884.161488] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1885.765847] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 1888.328531] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 1891.703297] Lustre: Unmounted lustre-client [ 1910.374514] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 1956.866807] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1962.354615] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1962.988748] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 1966.872873] Lustre: Unmounted lustre-client [ 2024.880888] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2031.425398] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2032.135678] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 2035.902244] Lustre: Unmounted lustre-client [ 2057.403744] Lustre: DEBUG MARKER: files compared the same [ 2081.504856] Key type lgssc unregistered [ 2081.723474] LNet: 50397:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2081.731404] LNetError: 50397:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2081.753992] LNet: Removed LNI 192.168.204.45@tcp [ 2082.424746] Key type .llcrypt unregistered [ 2082.426719] Key type ._llcrypt unregistered [ 2095.956744] Key type ._llcrypt registered [ 2095.960989] Key type .llcrypt registered [ 2097.218098] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 21:14:52 (1788311692) [ 2099.321918] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2099.339077] alg: No test for adler32 (adler32-zlib) [ 2100.453620] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [ 2100.751481] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 2102.399236] Key type lgssc registered [ 2103.492703] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2148.228772] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2152.749972] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2169.888763] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2175.527807] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 2179.327318] Lustre: Unmounted lustre-client [ 2207.119319] Key type lgssc unregistered [ 2207.455613] LNet: 53980:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2207.465882] LNetError: 53980:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2207.529694] LNet: Removed LNI 192.168.204.45@tcp [ 2208.349458] Key type .llcrypt unregistered [ 2208.355538] Key type ._llcrypt unregistered [ 2227.063295] Key type ._llcrypt registered [ 2227.066283] Key type .llcrypt registered [ 2228.516966] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 21:17:03 (1788311823) [ 2230.837202] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2230.845691] alg: No test for adler32 (adler32-zlib) [ 2231.920155] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [ 2232.132760] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 2233.879282] Key type lgssc registered [ 2235.150361] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2320.509917] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2354.767580] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2360.515119] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2389.776676] Key type lgssc unregistered [ 2390.129366] LNet: 57471:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2390.141849] LNetError: 57471:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2390.186294] LNet: Removed LNI 192.168.204.45@tcp [ 2390.987877] Key type .llcrypt unregistered [ 2390.991822] Key type ._llcrypt unregistered [ 2404.180570] Key type ._llcrypt registered [ 2404.182264] Key type .llcrypt registered [ 2405.430610] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 21:20:00 (1788312000) [ 2408.432616] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2408.441139] alg: No test for adler32 (adler32-zlib) [ 2409.648879] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [ 2409.934082] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 2411.599208] Key type lgssc registered [ 2412.845356] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2488.750817] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 2495.700940] Lustre: Unmounted lustre-client [ 2530.742831] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 21:22:05 (1788312125) [ 2645.127745] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2666.024427] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 2674.002708] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2674.010151] LustreError: 63944:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff994986f70800: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 2674.039408] Lustre: 58455:0:(llite_lib.c:4342:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.204.145@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 2684.396354] Lustre: Unmounted lustre-client [ 2736.431258] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 21:25:31 (1788312331) [ 2750.467376] Key type lgssc unregistered [ 2750.737095] LNet: 65162:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2750.752949] LNetError: 65162:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2750.796308] LNet: Removed LNI 192.168.204.45@tcp [ 2751.790969] Key type .llcrypt unregistered [ 2751.793773] Key type ._llcrypt unregistered [ 2766.705775] Key type ._llcrypt registered [ 2766.707310] Key type .llcrypt registered [ 2767.032525] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2767.051802] alg: No test for adler32 (adler32-zlib) [ 2768.166161] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [ 2768.484936] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 2770.199275] Key type lgssc registered [ 2771.507864] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2820.908861] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2826.163589] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2848.480701] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2850.121058] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 2857.821542] Lustre: Unmounted lustre-client [ 2893.533758] Key type lgssc unregistered [ 2893.735747] LNet: 68178:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2893.744838] LNetError: 68178:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2893.778721] LNet: Removed LNI 192.168.204.45@tcp [ 2894.836334] Key type .llcrypt unregistered [ 2894.840294] Key type ._llcrypt unregistered [ 2904.671261] Key type ._llcrypt registered [ 2904.676959] Key type .llcrypt registered [ 2904.851680] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2904.873760] alg: No test for adler32 (adler32-zlib) [ 2906.036468] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [ 2906.239916] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 2907.935185] Key type lgssc registered [ 2909.313454] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3022.956157] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3029.022860] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3050.009556] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3051.616479] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 3058.990826] Lustre: Unmounted lustre-client [ 3091.681637] Key type lgssc unregistered [ 3092.008933] LNet: 71608:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3092.016525] LNetError: 71608:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3092.030425] LNet: Removed LNI 192.168.204.45@tcp [ 3092.946969] Key type .llcrypt unregistered [ 3092.948461] Key type ._llcrypt unregistered [ 3109.618803] Key type ._llcrypt registered [ 3109.629975] Key type .llcrypt registered [ 3111.155228] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 21:31:45 (1788312705) [ 3113.295664] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3113.427588] alg: No test for adler32 (adler32-zlib) [ 3114.598727] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [ 3114.869784] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 3116.591207] Key type lgssc registered [ 3117.635548] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3171.312599] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3177.860177] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3203.360489] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3205.728871] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 3212.477621] Lustre: Unmounted lustre-client [ 3213.049709] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 3216.211541] Lustre: Unmounted lustre-client [ 3242.385554] Key type lgssc unregistered [ 3242.723289] LNet: 75302:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3242.742502] LNetError: 75302:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3242.767643] LNet: Removed LNI 192.168.204.45@tcp [ 3243.497645] Key type .llcrypt unregistered [ 3243.503361] Key type ._llcrypt unregistered [ 3255.082174] Key type ._llcrypt registered [ 3255.088549] Key type .llcrypt registered [ 3255.590609] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3255.606472] alg: No test for adler32 (adler32-zlib) [ 3256.632254] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [ 3256.974751] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 3258.743193] Key type lgssc registered [ 3259.938903] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3310.692666] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3316.125330] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3340.800876] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3342.517813] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 3350.469763] Lustre: Unmounted lustre-client [ 3379.644589] Key type lgssc unregistered [ 3379.961682] LNet: 78337:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3379.972928] LNetError: 78337:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3380.010259] LNet: Removed LNI 192.168.204.45@tcp [ 3380.666593] Key type .llcrypt unregistered [ 3380.671487] Key type ._llcrypt unregistered [ 3398.085297] Key type ._llcrypt registered [ 3398.087335] Key type .llcrypt registered [ 3399.747220] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 21:36:34 (1788312994) [ 3401.992179] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3402.011488] alg: No test for adler32 (adler32-zlib) [ 3403.152853] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [ 3403.427308] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 3405.135239] Key type lgssc registered [ 3406.255936] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3455.146126] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3461.149365] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3484.165944] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3486.017640] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 3503.131552] Lustre: lustre: nosquash_nids is cleared [ 3503.166049] Lustre: lustre: root_squash is set to 500:500 [ 3520.541422] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.204.45@tcp 192.168.0.[2,10]@tcp [ 3526.575193] Lustre: lustre: nosquash_nids set to 192.168.204.45/24@tcp [ 3530.435506] Lustre: Unmounted lustre-client [ 3553.369648] Key type lgssc unregistered [ 3553.753312] LNet: 82280:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3553.762032] LNetError: 82280:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3553.775064] LNet: Removed LNI 192.168.204.45@tcp [ 3554.426341] Key type .llcrypt unregistered [ 3554.429585] Key type ._llcrypt unregistered [ 3571.367203] Key type ._llcrypt registered [ 3571.376779] Key type .llcrypt registered [ 3573.313807] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 21:39:27 (1788313167) [ 3575.806291] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3577.554237] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 21:39:32 (1788313172) [ 3580.087229] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3580.103643] alg: No test for adler32 (adler32-zlib) [ 3581.187071] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [ 3581.445961] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 3583.127238] Key type lgssc registered [ 3584.349216] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3639.365966] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3646.529570] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3669.200239] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3669.558431] Lustre: lustre: root_squash is set to 500:500 [ 3669.586400] Lustre: lustre: nosquash_nids set to 192.168.204.45/24@tcp [ 3670.635623] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 3676.226959] Lustre: Unmounted lustre-client [ 3703.747918] Key type lgssc unregistered [ 3703.992872] LNet: 86237:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3704.009819] LNetError: 86237:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3705.079486] LNet: Removed LNI 192.168.204.45@tcp [ 3705.968497] Key type .llcrypt unregistered [ 3705.973755] Key type ._llcrypt unregistered [ 3722.297983] Key type ._llcrypt registered [ 3722.304258] Key type .llcrypt registered [ 3724.015719] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 3725.883478] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 21:42:00 (1788313320) [ 3745.037526] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3745.131657] alg: No test for adler32 (adler32-zlib) [ 3746.198139] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [ 3746.538543] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 3748.387567] Key type lgssc registered [ 3749.725144] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3833.430518] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3839.185538] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3861.114588] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3937.579328] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3942.651456] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3962.409680] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3974.213870] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 4007.568506] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff99498837a800.ost_server_uuid 50 [ 4009.100638] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff99498837a800.ost_server_uuid in IDLE state after 0 sec [ 4009.538659] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 4013.364778] Lustre: Unmounted lustre-client [ 4015.843714] Lustre: Unmounted lustre-client [ 4058.154356] Key type lgssc unregistered [ 4058.380066] LNet: 93906:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4058.385183] LNetError: 93906:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4058.402228] LNet: Removed LNI 192.168.204.45@tcp [ 4059.151603] Key type .llcrypt unregistered [ 4059.153877] Key type ._llcrypt unregistered [ 4091.091804] Key type ._llcrypt registered [ 4091.095744] Key type .llcrypt registered [ 4092.377793] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 21:48:07 (1788313687) [ 4112.431062] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4112.503092] alg: No test for adler32 (adler32-zlib) [ 4113.685660] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [ 4114.017955] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 4115.663251] Key type lgssc registered [ 4116.981961] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4203.453666] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4210.082214] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4236.194123] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4239.042892] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 4259.807613] Lustre: 95271:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788313840/real 1788313840] req@0000000042a4b8a9 x1875182836782336/t0(0) o400->lustre-OST0000-osc-ffff994987a3c800@192.168.204.145@tcp:28/4 lens 224/224 e 0 to 1 dl 1788313856 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4259.852292] Lustre: lustre-OST0000-osc-ffff994987a3c800: Connection to lustre-OST0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4264.973446] Lustre: 95271:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788313845/real 1788313845] req@00000000aedcd5af x1875182836782848/t0(0) o400->lustre-OST0000-osc-ffff994987a3c800@192.168.204.145@tcp:28/4 lens 224/224 e 0 to 1 dl 1788313861 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4271.135197] Lustre: 95271:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788313851/real 1788313851] req@000000008aebd6d9 x1875182836783360/t0(0) o400->lustre-OST0000-osc-ffff994987a3c800@192.168.204.145@tcp:28/4 lens 224/224 e 0 to 1 dl 1788313867 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4276.191207] Lustre: 95270:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788313856/real 1788313856] req@0000000026017c08 x1875182836783872/t0(0) o400->lustre-OST0000-osc-ffff994987a3c800@192.168.204.145@tcp:28/4 lens 224/224 e 0 to 1 dl 1788313872 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4280.301522] Lustre: lustre-MDT0000-mdc-ffff994987a3c800: Connection to lustre-MDT0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4296.671140] Lustre: 95273:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788313876/real 1788313876] req@00000000aedcd5af x1875182836785792/t0(0) o400->MGC192.168.204.145@tcp@192.168.204.145@tcp:26/25 lens 224/224 e 0 to 1 dl 1788313892 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4296.705916] LustreError: MGC192.168.204.145@tcp: Connection to MGS (at 192.168.204.145@tcp) was lost; in progress operations using this service will fail [ 4306.931489] Lustre: Evicted from MGS (at 192.168.204.145@tcp) after server handle changed from 0x3bc971ab622b0c1c to 0x3bc971ab622b0e99 [ 4306.954101] Lustre: MGC192.168.204.145@tcp: Connection restored to 192.168.204.145@tcp (at 192.168.204.145@tcp) [ 4311.434839] Lustre: lustre-MDT0000-mdc-ffff994987a3c800: Connection restored to 192.168.204.145@tcp (at 192.168.204.145@tcp) [ 4317.638194] Lustre: Unmounted lustre-client [ 4345.848841] Key type lgssc unregistered [ 4346.182289] LNet: 99577:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4346.192427] LNetError: 99577:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4346.226710] LNet: Removed LNI 192.168.204.45@tcp [ 4347.341903] Key type .llcrypt unregistered [ 4347.343978] Key type ._llcrypt unregistered [ 4363.657649] Key type ._llcrypt registered [ 4363.659584] Key type .llcrypt registered [ 4364.778249] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 21:52:39 (1788313959) [ 4378.785530] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4378.817381] alg: No test for adler32 (adler32-zlib) [ 4379.913664] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [ 4380.181437] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 4381.839778] Key type lgssc registered [ 4383.006569] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4421.843978] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4536.934697] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4543.329245] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4568.329880] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4570.114142] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 4572.551413] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 4574.499927] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 21:56:09 (1788314169) [ 4815.062622] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4825.384803] Lustre: Unmounted lustre-client [ 4973.975258] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4980.352996] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5010.088086] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5055.482370] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 22:04:09 (1788314649) [ 5167.478908] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5175.114627] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5203.453785] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5204.197524] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 5209.813212] Lustre: Unmounted lustre-client [ 5243.792396] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 22:07:18 (1788314838) [ 5351.491513] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5357.958613] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5383.023226] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5383.871572] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 5388.510533] Lustre: Unmounted lustre-client [ 5426.907840] Key type lgssc unregistered [ 5427.206332] LNet: 122630:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5427.214842] LNetError: 122630:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5427.251823] LNet: Removed LNI 192.168.204.45@tcp [ 5427.972814] Key type .llcrypt unregistered [ 5427.975739] Key type ._llcrypt unregistered [ 5446.507481] Key type ._llcrypt registered [ 5446.508960] Key type .llcrypt registered [ 5448.063865] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 22:10:42 (1788315042) [ 5450.443259] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5450.484974] alg: No test for adler32 (adler32-zlib) [ 5451.607762] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [ 5451.845906] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 5453.536393] Key type lgssc registered [ 5454.849941] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5514.145682] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5520.469943] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5544.672180] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5555.787709] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 5570.401348] Lustre: Unmounted lustre-client [ 5600.571720] Key type lgssc unregistered [ 5600.963694] LNet: 126229:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5600.979912] LNetError: 126229:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5601.018951] LNet: Removed LNI 192.168.204.45@tcp [ 5602.044480] Key type .llcrypt unregistered [ 5602.047884] Key type ._llcrypt unregistered [ 5618.151804] Key type ._llcrypt registered [ 5618.153960] Key type .llcrypt registered [ 5619.725632] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 22:13:34 (1788315214) [ 5622.100953] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5622.276130] alg: No test for adler32 (adler32-zlib) [ 5623.521596] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [ 5623.956772] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 5625.711193] Key type lgssc registered [ 5627.266932] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5687.793637] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5694.793333] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5718.489202] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5720.078243] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 5725.165444] Lustre: lustre-OST0000-osc-ffff994991a4f800: Connection to lustre-OST0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5738.843706] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff994991a4f800.ost_server_uuid 50 [ 5740.617226] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 5751.576819] Lustre: Unmounted lustre-client [ 5776.025663] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 22:16:10 (1788315370) [ 5815.580340] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5822.173416] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5847.381501] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5873.072282] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5873.657616] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 5878.773240] Lustre: lustre-OST0000-osc-ffff994985400000: Connection to lustre-OST0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5906.601300] Lustre: Unmounted lustre-client [ 5949.291493] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 22:19:04 (1788315544) [ 5981.971218] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5988.671631] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6012.246956] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6032.755492] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6033.212378] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 6038.507879] Lustre: lustre-OST0000-osc-ffff994990e82000: Connection to lustre-OST0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6054.529474] Lustre: Unmounted lustre-client [ 6101.211410] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 22:21:36 (1788315696) [ 6202.278254] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6210.176425] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6232.677669] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6300.596385] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6305.827816] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6324.724187] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6363.673038] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 6409.189691] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6410.820417] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6413.082836] Lustre: Unmounted lustre-client [ 6448.836750] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 22:27:23 (1788316043) [ 6484.584861] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6490.620064] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6510.425144] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6547.367625] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6575.294559] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 6622.205534] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6624.066142] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6637.221734] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6647.615446] Lustre: Unmounted lustre-client [ 6709.413231] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 22:31:44 (1788316304) [ 6746.402323] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6753.629839] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6772.218650] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6772.833484] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 6795.039639] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6796.469559] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6811.535414] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff99498837a800.ost_server_uuid 50 [ 6812.987634] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff99498837a800.ost_server_uuid in IDLE state after 0 sec [ 6816.449906] Lustre: Unmounted lustre-client [ 6816.790925] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 6816.796126] Lustre: Skipped 3 previous similar messages [ 6816.890909] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 6817.922680] LustreError: 149637:0:(sec.c:403:import_sec_validate_get()) import 000000001b34744d (NEW) with no sec [ 6876.756463] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 22:34:31 (1788316471) [ 6909.548161] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6913.483290] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6933.600875] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6953.907231] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6954.259232] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 6954.352607] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 6968.688436] Lustre: Unmounted lustre-client [ 6968.695995] Lustre: Skipped 1 previous similar message [ 7016.497738] Key type lgssc unregistered [ 7016.824898] LNet: 153063:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7016.842225] LNetError: 153063:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7016.874387] LNet: Removed LNI 192.168.204.45@tcp [ 7017.898060] Key type .llcrypt unregistered [ 7017.904742] Key type ._llcrypt unregistered [ 7035.995949] Key type ._llcrypt registered [ 7035.999132] Key type .llcrypt registered [ 7037.407685] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 22:37:12 (1788316632) [ 7038.791028] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7038.817812] alg: No test for adler32 (adler32-zlib) [ 7040.087230] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [ 7040.584259] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 7042.327185] Key type lgssc registered [ 7043.637345] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7100.663917] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7107.205993] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7131.677628] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7154.777628] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7155.181387] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7156.050717] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 7160.846808] LustreError: lustre-MDT0001-mdc-ffff9949850b2000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 7160.866615] Lustre: lustre-MDT0001-mdc-ffff9949850b2000: Connection restored to 192.168.204.145@tcp (at 192.168.204.145@tcp) [ 7167.961986] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7170.507641] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7195.148122] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7199.191440] Lustre: Unmounted lustre-client [ 7227.080223] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 22:40:21 (1788316821) [ 7330.733587] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7336.118538] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7357.341144] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7357.670851] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 7385.799189] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7387.453968] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7399.430478] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7409.846367] Lustre: Unmounted lustre-client [ 7445.319293] Key type lgssc unregistered [ 7445.530814] LNet: 162671:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7445.555321] LNetError: 162671:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7445.585479] LNet: Removed LNI 192.168.204.45@tcp [ 7446.252973] Key type .llcrypt unregistered [ 7446.255550] Key type ._llcrypt unregistered [ 7480.232725] Key type ._llcrypt registered [ 7480.242831] Key type .llcrypt registered [ 7481.538810] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 22:44:36 (1788317076) [ 7484.039670] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7484.047471] alg: No test for adler32 (adler32-zlib) [ 7485.114807] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [ 7485.364029] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 7487.048230] Key type lgssc registered [ 7488.302389] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7548.353456] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7554.760742] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7578.096967] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7580.812068] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 7628.204789] Lustre: Unmounted lustre-client [ 7668.252929] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7668.679763] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 7703.763955] Lustre: Unmounted lustre-client [ 7733.332590] Key type lgssc unregistered [ 7733.583835] LNet: 167130:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7733.627676] LNetError: 167130:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7733.672734] LNet: Removed LNI 192.168.204.45@tcp [ 7734.558613] Key type .llcrypt unregistered [ 7734.564684] Key type ._llcrypt unregistered [ 7748.320536] Key type ._llcrypt registered [ 7748.323387] Key type .llcrypt registered [ 7749.692678] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 22:49:04 (1788317344) [ 7751.748084] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7751.775715] alg: No test for adler32 (adler32-zlib) [ 7752.972036] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [ 7753.204600] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 7754.871243] Key type lgssc registered [ 7756.158178] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7808.889577] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7814.927178] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7839.043828] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7841.833970] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 7862.547208] Lustre: Unmounted lustre-client [ 7890.102655] Key type lgssc unregistered [ 7890.472751] LNet: 170892:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7890.483667] LNetError: 170892:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7890.496928] LNet: Removed LNI 192.168.204.45@tcp [ 7891.354269] Key type .llcrypt unregistered [ 7891.359597] Key type ._llcrypt unregistered [ 7905.077134] Key type ._llcrypt registered [ 7905.087397] Key type .llcrypt registered [ 7905.539652] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7905.550571] alg: No test for adler32 (adler32-zlib) [ 7906.685732] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [ 7907.022524] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 7908.735609] Key type lgssc registered [ 7909.861720] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7965.938635] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7970.511612] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7992.357175] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7993.959093] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 8005.893242] Lustre: Unmounted lustre-client [ 8035.448055] Key type lgssc unregistered [ 8035.837311] LNet: 173961:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8035.849058] LNetError: 173961:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8035.866979] LNet: Removed LNI 192.168.204.45@tcp [ 8036.464835] Key type .llcrypt unregistered [ 8036.467225] Key type ._llcrypt unregistered [ 8054.527640] Key type ._llcrypt registered [ 8054.530761] Key type .llcrypt registered [ 8056.291450] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 22:54:10 (1788317650) [ 8059.107982] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8059.146957] alg: No test for adler32 (adler32-zlib) [ 8060.286856] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [ 8060.550915] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 8062.295239] Key type lgssc registered [ 8063.513940] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8116.352506] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8121.501910] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8148.652988] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8150.394681] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 8171.676396] Lustre: Unmounted lustre-client [ 8201.093506] Key type lgssc unregistered [ 8201.360676] LNet: 177724:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8201.369257] LNetError: 177724:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8201.407230] LNet: Removed LNI 192.168.204.45@tcp [ 8202.287695] Key type .llcrypt unregistered [ 8202.292362] Key type ._llcrypt unregistered [ 8217.714871] Key type ._llcrypt registered [ 8217.720120] Key type .llcrypt registered [ 8218.540537] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8218.568701] alg: No test for adler32 (adler32-zlib) [ 8219.889339] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [ 8220.243878] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 8221.951253] Key type lgssc registered [ 8223.554065] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8281.437707] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8287.945953] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8312.905795] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8324.236976] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 8332.890490] Lustre: Unmounted lustre-client [ 8359.529205] Key type lgssc unregistered [ 8359.870420] LNet: 180797:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8359.887745] LNetError: 180797:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8359.910291] LNet: Removed LNI 192.168.204.45@tcp [ 8360.959826] Key type .llcrypt unregistered [ 8360.962053] Key type ._llcrypt unregistered [ 8382.148491] Key type ._llcrypt registered [ 8382.152775] Key type .llcrypt registered [ 8383.920933] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 22:59:38 (1788317978) [ 8406.437334] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8406.459455] alg: No test for adler32 (adler32-zlib) [ 8407.610111] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [ 8407.862778] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 8409.625624] Key type lgssc registered [ 8411.134937] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8507.822709] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8516.026880] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8541.491459] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8584.550001] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 23:02:59 (1788318179) [ 8618.942621] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8625.203623] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8646.772889] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8648.562996] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 8657.314348] Lustre: Unmounted lustre-client [ 8685.223878] Key type lgssc unregistered [ 8685.482361] LNet: 187921:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8685.492114] LNetError: 187921:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8685.509806] LNet: Removed LNI 192.168.204.45@tcp [ 8686.439897] Key type .llcrypt unregistered [ 8686.443219] Key type ._llcrypt unregistered [ 8702.734605] Key type ._llcrypt registered [ 8702.736489] Key type .llcrypt registered [ 8704.264826] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 8706.564103] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 23:05:01 (1788318301) [ 8724.760100] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8724.776555] alg: No test for adler32 (adler32-zlib) [ 8725.852421] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [ 8726.125051] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 8727.928154] Key type lgssc registered [ 8729.265475] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8829.038510] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8836.011726] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8861.166111] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8884.081092] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8886.753502] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 8910.588237] Lustre: Unmounted lustre-client [ 9047.429500] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9053.051585] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9077.980331] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9121.837311] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 23:11:56 (1788318716) [ 9123.521629] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 9125.235379] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 23:12:00 (1788318720) [ 9226.018257] Key type lgssc unregistered [ 9226.345326] LNet: 199375:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9226.356714] LNetError: 199375:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9226.381517] LNet: Removed LNI 192.168.204.45@tcp [ 9227.245245] Key type .llcrypt unregistered [ 9227.248556] Key type ._llcrypt unregistered [ 9245.435072] Key type ._llcrypt registered [ 9245.439892] Key type .llcrypt registered [ 9246.863845] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 23:14:01 (1788318841) [ 9292.457810] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9292.472437] alg: No test for adler32 (adler32-zlib) [ 9293.465667] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [ 9293.685678] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 9295.375752] Key type lgssc registered [ 9296.341155] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9349.502164] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9355.630914] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9392.365081] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9430.177302] Key type lgssc unregistered [ 9430.523962] LNet: 203119:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9430.530671] LNetError: 203119:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9430.554268] LNet: Removed LNI 192.168.204.45@tcp [ 9431.286678] Key type .llcrypt unregistered [ 9431.290458] Key type ._llcrypt unregistered [ 9447.939355] Key type ._llcrypt registered [ 9447.941404] Key type .llcrypt registered [ 9449.401588] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 23:17:24 (1788319044) [ 9452.260861] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9452.307873] alg: No test for adler32 (adler32-zlib) [ 9453.360380] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [ 9453.555180] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 9455.224457] Key type lgssc registered [ 9456.183967] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9507.614711] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9512.153963] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9532.582592] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9534.101204] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [ 9539.563450] Lustre: lustre-MDT0000-mdc-ffff994987784000: Connection to lustre-MDT0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9549.801764] Lustre: lustre-MDT0001-mdc-ffff994987784000: Connection to lustre-MDT0001 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9566.175213] Lustre: 204111:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788319146/real 1788319146] req@0000000000a6c853 x1875188435206144/t0(0) o400->MGC192.168.204.145@tcp@192.168.204.145@tcp:26/25 lens 224/224 e 0 to 1 dl 1788319162 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9566.217611] LustreError: MGC192.168.204.145@tcp: Connection to MGS (at 192.168.204.145@tcp) was lost; in progress operations using this service will fail [ 9576.500164] Lustre: Evicted from MGS (at 192.168.204.145@tcp) after server handle changed from 0xe71d99e54af065e5 to 0xe71d99e54af082e8 [ 9576.520818] Lustre: MGC192.168.204.145@tcp: Connection restored to 192.168.204.145@tcp (at 192.168.204.145@tcp) [ 9595.379421] LustreError: lustre-MDT0000-mdc-ffff994987784000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 9595.453436] Lustre: lustre-MDT0000-mdc-ffff994987784000: Connection restored to 192.168.204.145@tcp (at 192.168.204.145@tcp) [ 9600.109276] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9601.547889] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9607.198708] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9608.826900] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9611.620154] Lustre: Unmounted lustre-client [ 9645.305973] Key type lgssc unregistered [ 9645.559407] LNet: 208068:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9645.571414] LNetError: 208068:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9645.595219] LNet: Removed LNI 192.168.204.45@tcp [ 9646.272677] Key type .llcrypt unregistered [ 9646.274412] Key type ._llcrypt unregistered [ 9661.086657] Key type ._llcrypt registered [ 9661.088539] Key type .llcrypt registered [ 9662.695054] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 23:20:57 (1788319257) [ 9665.295049] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9665.329251] alg: No test for adler32 (adler32-zlib) [ 9666.395661] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [ 9666.618412] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 9668.303205] Key type lgssc registered [ 9669.844388] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9725.036531] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9731.158088] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9787.435980] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9793.634983] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9829.830531] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9853.102742] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9893.050495] Key type lgssc unregistered [ 9893.306617] LNet: 213364:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9893.314118] LNetError: 213364:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9893.341218] LNet: Removed LNI 192.168.204.45@tcp [ 9894.001552] Key type .llcrypt unregistered [ 9894.003572] Key type ._llcrypt unregistered [ 9937.050174] Key type ._llcrypt registered [ 9937.052649] Key type .llcrypt registered [ 9938.606995] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 23:25:33 (1788319533) [ 9977.341927] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9977.381080] alg: No test for adler32 (adler32-zlib) [ 9978.492675] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [ 9978.749808] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 9980.503627] Key type lgssc registered [ 9981.731949] Lustre: Echo OBD driver; http://www.lustre.org/ [10067.749167] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10073.270815] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10102.567834] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10148.386865] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 23:29:02 (1788319742) [10158.249717] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 23:29:13 (1788319753) [10192.711415] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10197.293452] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10217.125475] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10219.657846] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [10225.860789] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [10227.548449] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [10228.981930] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [10231.060695] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [10235.373313] Lustre: lustre-MDT0000-mdc-ffff9949895eb000: Connection to lustre-MDT0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [10251.743226] Lustre: 215258:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788319831/real 1788319831] req@0000000069a28c08 x1875188986685184/t0(0) o400->MGC192.168.204.145@tcp@192.168.204.145@tcp:26/25 lens 224/224 e 0 to 1 dl 1788319847 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10251.784932] LustreError: MGC192.168.204.145@tcp: Connection to MGS (at 192.168.204.145@tcp) was lost; in progress operations using this service will fail [10251.838251] Lustre: Evicted from MGS (at 192.168.204.145@tcp) after server handle changed from 0x684663b866005e7 to 0x684663b8660075a [10251.851460] Lustre: MGC192.168.204.145@tcp: Connection restored to 192.168.204.145@tcp (at 192.168.204.145@tcp) [10265.478421] Lustre: lustre-MDT0000-mdc-ffff9949895eb000: Connection restored to 192.168.204.145@tcp (at 192.168.204.145@tcp) [10274.508436] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10276.023760] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10279.909131] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [10282.495256] Lustre: lustre-MDT0000-mdc-ffff9949895eb000: Connection to lustre-MDT0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [10297.823928] Lustre: 215260:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788319878/real 1788319878] req@00000000741e5a0b x1875188986694528/t0(0) o400->MGC192.168.204.145@tcp@192.168.204.145@tcp:26/25 lens 224/224 e 0 to 1 dl 1788319894 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10297.876197] LustreError: MGC192.168.204.145@tcp: Connection to MGS (at 192.168.204.145@tcp) was lost; in progress operations using this service will fail [10308.080661] Lustre: Evicted from MGS (at 192.168.204.145@tcp) after server handle changed from 0x684663b8660075a to 0x684663b86600a71 [10308.095184] Lustre: MGC192.168.204.145@tcp: Connection restored to 192.168.204.145@tcp (at 192.168.204.145@tcp) [10318.252905] Lustre: lustre-MDT0000-mdc-ffff9949895eb000: Connection restored to 192.168.204.145@tcp (at 192.168.204.145@tcp) [10328.503419] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10330.175403] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10335.125366] Lustre: Unmounted lustre-client [10404.187643] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10410.507535] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10434.872884] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10435.512509] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [10442.282915] Lustre: Unmounted lustre-client [10470.986280] Key type lgssc unregistered [10471.272948] LNet: 225840:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10471.281869] LNetError: 225840:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10471.301832] LNet: Removed LNI 192.168.204.45@tcp [10472.111632] Key type .llcrypt unregistered [10472.114332] Key type ._llcrypt unregistered [10491.367957] Key type ._llcrypt registered [10491.370945] Key type .llcrypt registered [10492.607807] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 23:34:47 (1788320087) [10496.315156] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10496.383726] alg: No test for adler32 (adler32-zlib) [10497.344109] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [10497.520405] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [10499.175442] Key type lgssc registered [10500.101714] Lustre: Echo OBD driver; http://www.lustre.org/ [10549.677963] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10556.103482] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10579.462609] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10581.145504] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [10591.311806] Lustre: Unmounted lustre-client [10656.309977] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10661.994915] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10684.024892] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10685.100684] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [10694.800494] Lustre: Unmounted lustre-client [10765.555504] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10771.522725] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10794.456297] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10795.320403] LustreError: 226825:0:(lmv_obd.c:211:lmv_notify()) activation of lustre-MDT0001_UUID failed: -22 [10795.382937] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [10797.976472] Lustre: Unmounted lustre-client [10825.972989] Key type lgssc unregistered [10826.258212] LNet: 232888:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10826.275842] LNetError: 232888:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10826.302938] LNet: Removed LNI 192.168.204.45@tcp [10827.100869] Key type .llcrypt unregistered [10827.108564] Key type ._llcrypt unregistered [10845.262238] Key type ._llcrypt registered [10845.264962] Key type .llcrypt registered [10846.575936] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 23:40:41 (1788320441) [10849.575840] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10849.614939] alg: No test for adler32 (adler32-zlib) [10850.702236] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [10850.911738] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [10852.663218] Key type lgssc registered [10854.061423] Lustre: Echo OBD driver; http://www.lustre.org/ [10900.082831] Key type lgssc unregistered [10900.440795] LNet: 235214:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10900.455583] LNetError: 235214:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10900.480388] LNet: Removed LNI 192.168.204.45@tcp [10901.319683] Key type .llcrypt unregistered [10901.331784] Key type ._llcrypt unregistered [10933.953612] Key type ._llcrypt registered [10933.978706] Key type .llcrypt registered [10934.489940] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10934.510796] alg: No test for adler32 (adler32-zlib) [10935.694588] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [10935.968283] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [10937.759576] Key type lgssc registered [10939.178876] Lustre: Echo OBD driver; http://www.lustre.org/ [11019.464722] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11025.890156] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11050.054517] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11092.051775] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [11094.000862] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 23:44:48 (1788320688) [11128.664065] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11134.792791] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11157.521430] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11181.172859] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11182.925254] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [11188.207265] Lustre: lustre-OST0001-osc-ffff994985120000: Connection to lustre-OST0001 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [11196.628688] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11206.930514] Lustre: Unmounted lustre-client [11250.885972] Key type lgssc unregistered [11251.143953] LNet: 242328:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11251.150635] LNetError: 242328:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11251.179832] LNet: Removed LNI 192.168.204.45@tcp [11252.162681] Key type .llcrypt unregistered [11252.167533] Key type ._llcrypt unregistered [11287.101816] Key type ._llcrypt registered [11287.105222] Key type .llcrypt registered [11288.478372] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 23:48:03 (1788320883) [11302.433940] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11302.468526] alg: No test for adler32 (adler32-zlib) [11303.538919] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [11303.772642] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [11305.439894] Key type lgssc registered [11306.685473] Lustre: Echo OBD driver; http://www.lustre.org/ [11354.651339] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11360.367545] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11380.863133] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 23:49:35 (1788320975) [11412.113384] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11417.052566] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11441.720295] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11444.585579] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [11450.152691] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [11462.204714] Lustre: Unmounted lustre-client [11561.589305] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11567.268985] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11618.895493] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11619.539727] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [11622.402260] Lustre: Unmounted lustre-client [11648.541808] Key type lgssc unregistered [11648.805884] LNet: 250020:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11648.817024] LNetError: 250020:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11648.836765] LNet: Removed LNI 192.168.204.45@tcp [11649.528719] Key type .llcrypt unregistered [11649.531167] Key type ._llcrypt unregistered [11680.929824] Key type ._llcrypt registered [11680.936372] Key type .llcrypt registered [11681.520985] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11681.541570] alg: No test for adler32 (adler32-zlib) [11682.645965] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [11682.882281] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [11684.575219] Key type lgssc registered [11686.068298] Lustre: Echo OBD driver; http://www.lustre.org/ [11778.272412] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11783.312295] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11805.316380] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11855.910640] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [11857.833115] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 23:57:32 (1788321452) [11891.711549] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11898.181121] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11920.471423] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11925.423830] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [11928.964401] Lustre: Unmounted lustre-client [11955.607595] Key type lgssc unregistered [11955.894254] LNet: 256658:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11955.910225] LNetError: 256658:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11955.932662] LNet: Removed LNI 192.168.204.45@tcp [11956.690604] Key type .llcrypt unregistered [11956.694887] Key type ._llcrypt unregistered [11971.438395] Key type ._llcrypt registered [11971.440919] Key type .llcrypt registered [11972.704854] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [11974.283742] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 23:59:29 (1788321569) [11982.514648] Key type .llcrypt unregistered [11982.519111] Key type ._llcrypt unregistered [11991.243862] Key type ._llcrypt registered [11991.246790] Key type .llcrypt registered [11991.808259] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11991.822852] alg: No test for adler32 (adler32-zlib) [11993.044698] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [11993.401203] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [11995.135185] Key type lgssc registered [11996.822550] Lustre: Echo OBD driver; http://www.lustre.org/ [12049.417796] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12066.027777] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [12069.098756] Lustre: Unmounted lustre-client [12104.775717] Key type lgssc unregistered [12104.997697] LNet: 260141:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12105.008981] LNetError: 260141:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12105.036973] LNet: Removed LNI 192.168.204.45@tcp [12105.748314] Key type .llcrypt unregistered [12105.750039] Key type ._llcrypt unregistered [12123.468447] Key type ._llcrypt registered [12123.469955] Key type .llcrypt registered [12124.776703] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 00:01:59 (1788321719) [12127.238064] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12127.393353] alg: No test for adler32 (adler32-zlib) [12128.597727] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [12128.941495] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [12130.607270] Key type lgssc registered [12132.265475] Lustre: Echo OBD driver; http://www.lustre.org/ [12219.912675] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12254.397922] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12259.969183] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12262.631533] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [12265.403421] Lustre: Unmounted lustre-client [12287.432345] Key type lgssc unregistered [12287.719247] LNet: 263687:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12287.731945] LNetError: 263687:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12287.754640] LNet: Removed LNI 192.168.204.45@tcp [12288.445154] Key type .llcrypt unregistered [12288.449278] Key type ._llcrypt unregistered [12303.469233] Key type ._llcrypt registered [12303.471669] Key type .llcrypt registered [12304.746210] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 00:04:59 (1788321899) [12307.147406] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12307.221387] alg: No test for adler32 (adler32-zlib) [12308.389394] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [12308.708830] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [12310.407308] Key type lgssc registered [12311.699120] Lustre: Echo OBD driver; http://www.lustre.org/ [12361.800914] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12368.039606] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12388.679954] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12390.569705] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [12396.008439] Lustre: lustre-MDT0000-mdc-ffff994985d5c000: Connection to lustre-MDT0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [12397.771294] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12397.822726] LustreError: 266848:0:(file.c:6166:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [12398.295186] LustreError: MGC192.168.204.145@tcp: Connection to MGS (at 192.168.204.145@tcp) was lost; in progress operations using this service will fail [12398.409178] Lustre: Unmounted lustre-client [12427.010638] Key type lgssc unregistered [12427.340070] LNet: 267266:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12427.351760] LNetError: 267266:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12427.377182] LNet: Removed LNI 192.168.204.45@tcp [12428.366315] Key type .llcrypt unregistered [12428.369667] Key type ._llcrypt unregistered [12445.871698] Key type ._llcrypt registered [12445.874939] Key type .llcrypt registered [12447.430594] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 00:07:22 (1788322042) [12450.253857] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12450.295356] alg: No test for adler32 (adler32-zlib) [12451.466080] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [12451.729689] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [12453.399283] Key type lgssc registered [12454.747567] Lustre: Echo OBD driver; http://www.lustre.org/ [12508.734574] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12513.306434] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12537.787734] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12540.650191] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [12546.027410] Lustre: lustre-MDT0001-mdc-ffff99498aa35800: Connection to lustre-MDT0001 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [12551.189888] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [12553.904471] Lustre: Unmounted lustre-client [12575.229815] Key type lgssc unregistered [12575.552563] LNet: 270852:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12575.561898] LNetError: 270852:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12575.574407] LNet: Removed LNI 192.168.204.45@tcp [12576.349686] Key type .llcrypt unregistered [12576.351548] Key type ._llcrypt unregistered [12592.415876] Key type ._llcrypt registered [12592.418370] Key type .llcrypt registered [12593.501591] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 00:09:48 (1788322188) [12612.277935] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12612.319549] alg: No test for adler32 (adler32-zlib) [12613.534603] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [12613.776513] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [12615.447253] Key type lgssc registered [12617.068431] Lustre: Echo OBD driver; http://www.lustre.org/ [12734.454854] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12737.164794] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [12781.551263] Lustre: lustre-MDT0001-mdc-ffff994985124800: Connection to lustre-MDT0001 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [12793.928442] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12804.270895] Lustre: Unmounted lustre-client [12833.583922] Key type lgssc unregistered [12833.943805] LNet: 276661:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12833.953169] LNetError: 276661:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12833.982973] LNet: Removed LNI 192.168.204.45@tcp [12834.721346] Key type .llcrypt unregistered [12834.728939] Key type ._llcrypt unregistered [12851.452604] Key type ._llcrypt registered [12851.455415] Key type .llcrypt registered [12853.015911] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 00:14:07 (1788322447) [12854.754216] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [12856.478978] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 00:14:11 (1788322451) [12858.091471] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [12859.820543] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 00:14:14 (1788322454) [12861.735771] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [12863.637281] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 00:14:18 (1788322458) [12865.361240] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [12867.281660] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 00:14:22 (1788322462) [12869.118776] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [12871.095242] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 00:14:25 (1788322465) [12881.230466] Key type .llcrypt unregistered [12881.234078] Key type ._llcrypt unregistered [12890.570100] Key type ._llcrypt registered [12890.601077] Key type .llcrypt registered [12891.027347] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12891.058522] alg: No test for adler32 (adler32-zlib) [12892.222813] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [12892.492856] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [12894.167190] Key type lgssc registered [12895.613440] Lustre: Echo OBD driver; http://www.lustre.org/ [12981.365957] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12987.760982] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13012.624773] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13014.316033] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [13016.992781] Lustre: Unmounted lustre-client [13054.658752] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 00:17:29 (1788322649) [13088.477903] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13093.615387] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13128.260098] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13134.391949] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [13136.330647] Lustre: Unmounted lustre-client [13178.436732] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 00:19:33 (1788322773) [13181.279363] LNet: 1 local NIs in recovery (showing 1): 192.168.204.45@tcp [13829.120819] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13835.641975] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13872.629226] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13978.174213] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 00:32:52 (1788323572) [13990.601155] Key type lgssc unregistered [13990.977078] LNet: 290203:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13990.998295] LNetError: 290203:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13991.033889] LNet: Removed LNI 192.168.204.45@tcp [13991.858392] Key type .llcrypt unregistered [13991.862231] Key type ._llcrypt unregistered [14119.994711] Key type ._llcrypt registered [14120.001222] Key type .llcrypt registered [14120.546141] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14120.559105] alg: No test for adler32 (adler32-zlib) [14121.676824] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [14121.949591] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [14123.618671] Key type lgssc registered [14124.727089] Lustre: Echo OBD driver; http://www.lustre.org/ [14212.733450] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14218.461096] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14245.553370] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14288.756273] Key type lgssc unregistered [14289.056861] LNet: 293909:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14289.068530] LNetError: 293909:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14289.089444] LNet: Removed LNI 192.168.204.45@tcp [14289.833226] Key type .llcrypt unregistered [14289.837845] Key type ._llcrypt unregistered [14326.651650] Key type ._llcrypt registered [14326.654178] Key type .llcrypt registered [14327.857636] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 00:38:42 (1788323922) [14347.633419] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14347.665248] alg: No test for adler32 (adler32-zlib) [14348.759728] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [14349.079815] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [14350.808379] Key type lgssc registered [14352.026892] Lustre: Echo OBD driver; http://www.lustre.org/ [14403.144648] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 00:39:58 (1788323998) [14415.443252] Key type lgssc unregistered [14415.778307] LNet: 297468:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14415.790237] LNetError: 297468:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14415.813581] LNet: Removed LNI 192.168.204.45@tcp [14416.537758] Key type .llcrypt unregistered [14416.541584] Key type ._llcrypt unregistered [14429.281528] Key type ._llcrypt registered [14429.291908] Key type .llcrypt registered [14429.842743] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14429.870766] alg: No test for adler32 (adler32-zlib) [14430.960392] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [14431.228794] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [14432.943189] Key type lgssc registered [14434.428713] Lustre: Echo OBD driver; http://www.lustre.org/ [14490.145763] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14496.042569] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14519.601941] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14792.124772] LNet: Added LNI 192.168.204.45@tcp50 [8/256/0/180] [14794.859966] LNet: Removed LNI 192.168.204.45@tcp [14797.401192] Lustre: 301871:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff994986605800: config has no valid NIDs, force dynamic NIDs [14798.684452] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [14798.872927] Lustre: Unmounted lustre-client [14801.425490] Key type lgssc unregistered [14801.754471] LNet: 302133:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14801.760919] LNetError: 302133:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14801.793984] LNet: Removed LNI 192.168.204.45@tcp50 [14802.826111] Key type .llcrypt unregistered [14802.835922] Key type ._llcrypt unregistered [14819.520437] Key type ._llcrypt registered [14819.548344] Key type .llcrypt registered [14819.705243] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14819.715443] alg: No test for adler32 (adler32-zlib) [14820.730523] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [14820.866372] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [14822.527292] Key type lgssc registered [14823.515708] Lustre: Echo OBD driver; http://www.lustre.org/ [14885.423852] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14889.640245] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14894.812451] LNet: Added LNI 192.168.204.45@tcp50 [8/256/0/180] [14895.445431] LNet: Removed LNI 192.168.204.45@tcp [14896.603213] Lustre: 304828:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff994988ab9800: config has no valid NIDs, force dynamic NIDs [14897.741133] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [14898.174410] Lustre: Unmounted lustre-client [14931.022826] Key type lgssc unregistered [14931.422663] LNet: 305702:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14931.428585] LNetError: 305702:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14931.444183] LNet: Removed LNI 192.168.204.45@tcp50 [14932.020452] Key type .llcrypt unregistered [14932.023981] Key type ._llcrypt unregistered [14944.494808] Key type ._llcrypt registered [14944.497342] Key type .llcrypt registered [14945.715538] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 00:49:00 (1788324540) [14963.483330] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14963.521637] alg: No test for adler32 (adler32-zlib) [14964.586770] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [14964.876657] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [14966.567397] Key type lgssc registered [14967.826788] Lustre: Echo OBD driver; http://www.lustre.org/ [15042.193629] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15047.988561] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15072.869442] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15103.935949] Key type lgssc unregistered [15104.222838] LNet: 310452:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15104.245372] LNetError: 310452:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15104.260857] LNet: Removed LNI 192.168.204.45@tcp [15104.904732] Key type .llcrypt unregistered [15104.912259] Key type ._llcrypt unregistered [15115.281790] Key type ._llcrypt registered [15115.283625] Key type .llcrypt registered [15116.741811] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 00:51:51 (1788324711) [15118.836971] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15118.852890] alg: No test for adler32 (adler32-zlib) [15119.943334] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [15120.150252] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [15121.818410] Key type lgssc registered [15122.885590] Lustre: Echo OBD driver; http://www.lustre.org/ [15175.660331] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15181.267268] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15202.543461] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15204.229714] Lustre: Mounted lustre-client - version 2.17.57_45_g07d9be3 [15266.696239] Lustre: Mounted fs15246-client - version 2.17.57_45_g07d9be3 [15281.907638] Lustre: Unmounted fs15246-client [15308.567359] Lustre: Unmounted lustre-client [15336.856613] Key type lgssc unregistered [15337.190557] LNet: 315319:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15337.198086] LNetError: 315319:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15337.227552] LNet: Removed LNI 192.168.204.45@tcp [15338.063130] Key type .llcrypt unregistered [15338.067937] Key type ._llcrypt unregistered [15350.343951] Key type ._llcrypt registered [15350.346138] Key type .llcrypt registered [15351.682873] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 00:55:46 (1788324946) [15353.661541] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15353.679252] alg: No test for adler32 (adler32-zlib) [15354.842817] Lustre: Lustre: Build Version: 2.17.57_45_g07d9be3 [15355.133249] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [15356.855227] Key type lgssc registered [15358.246066] Lustre: Echo OBD driver; http://www.lustre.org/ [15496.557291] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15503.561380] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15528.644718] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15644.025685] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 15350 sec ========= 01:00:38 (1788325238) [15646.579964] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 01:00:40 (1788325240) === [15651.212077] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 01:00:45 (1788325245) === [15687.989825] Key type lgssc unregistered [15688.259593] LNet: 324607:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15688.266291] LNetError: 324607:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15688.293940] LNet: Removed LNI 192.168.204.45@tcp [15689.038569] Key type .llcrypt unregistered [15689.048527] Key type ._llcrypt unregistered