[ 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 465474109 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: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001013] APIC: Switch to symmetric I/O mode setup [ 0.002379] x2apic enabled [ 0.003010] Switched APIC routing to physical x2apic. [ 0.005011] kvm-guest: setup PV IPIs [ 0.008457] ..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.009026] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.010019] pid_max: default: 32768 minimum: 301 [ 0.011158] LSM: Security Framework initializing [ 0.012059] Yama: becoming mindful. [ 0.013053] SELinux: Initializing. [ 0.015090] *** VALIDATE selinux *** [ 0.024530] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.029145] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.030170] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.032040] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.033140] *** VALIDATE tmpfs *** [ 0.035395] *** VALIDATE proc *** [ 0.037047] *** VALIDATE cgroup *** [ 0.038012] *** VALIDATE cgroup2 *** [ 0.040045] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.041189] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.042014] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.043041] Spectre V2 : User space: Vulnerable [ 0.044012] Speculative Store Bypass: Vulnerable [ 0.047836] debug: unmapping init [mem 0xffffffff98e59000-0xffffffff98e60fff] [ 0.049962] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.050644] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.051028] ... version: 2 [ 0.052015] ... bit width: 48 [ 0.053013] ... generic registers: 4 [ 0.054015] ... value mask: 0000ffffffffffff [ 0.055017] ... max period: 00007fffffffffff [ 0.056015] ... fixed-purpose events: 3 [ 0.057015] ... event mask: 000000070000000f [ 0.058315] rcu: Hierarchical SRCU implementation. [ 0.060608] smp: Bringing up secondary CPUs ... [ 0.061616] x86: Booting SMP configuration: [ 0.062036] .... node #0, CPUs: #1 #2 #3 [ 0.065422] smp: Brought up 1 node, 4 CPUs [ 0.067014] smpboot: Max logical packages: 1 [ 0.068020] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.182025] node 0 deferred pages initialised in 112ms [ 0.185218] devtmpfs: initialized [ 0.186235] x86/mm: Memory block size: 128MB [ 0.188812] gcov: version magic: 0x41383552 [ 0.190360] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.191098] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.192251] pinctrl core: initialized pinctrl subsystem [ 0.193211] [ 0.193752] ************************************************************* [ 0.194016] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.195017] ** ** [ 0.196013] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.197013] ** ** [ 0.198014] ** This means that this kernel is built to expose internal ** [ 0.199016] ** IOMMU data structures, which may compromise security on ** [ 0.200015] ** your system. ** [ 0.201014] ** ** [ 0.202013] ** If you see this message and you are not debugging the ** [ 0.203014] ** kernel, report this immediately to your vendor! ** [ 0.204012] ** ** [ 0.205014] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.206014] ************************************************************* [ 0.207632] NET: Registered protocol family 16 [ 0.208443] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.209060] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.210059] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.211431] cpuidle: using governor menu [ 0.213916] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.217811] PCI: Using configuration type 1 for base access [ 0.220159] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.229117] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.230046] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.232079] cryptd: max_cpu_qlen set to 1000 [ 0.236241] ACPI: Added _OSI(Module Device) [ 0.237011] ACPI: Added _OSI(Processor Device) [ 0.238009] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.239013] ACPI: Added _OSI(Processor Aggregator Device) [ 0.242817] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.244620] ACPI: Interpreter enabled [ 0.246078] ACPI: PM: (supports S0 S3 S4 S5) [ 0.247023] ACPI: Using IOAPIC for interrupt routing [ 0.249029] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.253502] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.264264] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.268050] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.271022] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.275085] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.281330] acpiphp: Slot [2] registered [ 0.283143] acpiphp: Slot [5] registered [ 0.284118] acpiphp: Slot [6] registered [ 0.286118] acpiphp: Slot [3] registered [ 0.287138] acpiphp: Slot [4] registered [ 0.289116] acpiphp: Slot [7] registered [ 0.291120] acpiphp: Slot [8] registered [ 0.292544] acpiphp: Slot [9] registered [ 0.294149] acpiphp: Slot [10] registered [ 0.296108] acpiphp: Slot [11] registered [ 0.297134] acpiphp: Slot [12] registered [ 0.300208] acpiphp: Slot [13] registered [ 0.302173] acpiphp: Slot [14] registered [ 0.304159] acpiphp: Slot [15] registered [ 0.306126] acpiphp: Slot [16] registered [ 0.307093] acpiphp: Slot [17] registered [ 0.308105] acpiphp: Slot [18] registered [ 0.310146] acpiphp: Slot [19] registered [ 0.311128] acpiphp: Slot [20] registered [ 0.313140] acpiphp: Slot [21] registered [ 0.314122] acpiphp: Slot [22] registered [ 0.316142] acpiphp: Slot [23] registered [ 0.318155] acpiphp: Slot [24] registered [ 0.319128] acpiphp: Slot [25] registered [ 0.321189] acpiphp: Slot [26] registered [ 0.323171] acpiphp: Slot [27] registered [ 0.324133] acpiphp: Slot [28] registered [ 0.326130] acpiphp: Slot [29] registered [ 0.327134] acpiphp: Slot [30] registered [ 0.329149] acpiphp: Slot [31] registered [ 0.331057] PCI host bridge to bus 0000:00 [ 0.332028] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.335030] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.338030] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.340030] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.343027] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.346032] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.348188] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.352062] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.355206] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.362982] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.367062] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.371024] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.373019] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.376025] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.379824] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.383904] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.387061] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.391792] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.395982] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.406917] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.412015] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.417029] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.426017] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.432020] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.449016] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.461291] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.467021] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.475016] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.491026] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.503660] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.507574] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.509466] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.512479] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.515293] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.521039] iommu: Default domain type: Passthrough [ 0.522408] SCSI subsystem initialized [ 0.523179] ACPI: bus type USB registered [ 0.525157] usbcore: registered new interface driver usbfs [ 0.527127] usbcore: registered new interface driver hub [ 0.530091] usbcore: registered new device driver usb [ 0.532294] pps_core: LinuxPPS API ver. 1 registered [ 0.534201] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.538075] PTP clock support registered [ 0.540131] EDAC MC: Ver: 3.0.0 [ 0.542224] PCI: Using ACPI for IRQ routing [ 0.545053] NetLabel: Initializing [ 0.546016] NetLabel: domain hash size = 128 [ 0.548018] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.550115] NetLabel: unlabeled traffic allowed by default [ 0.552203] vgaarb: loaded [ 0.554323] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.556017] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.561015] clocksource: Switched to clocksource kvm-clock [ 0.673187] VFS: Disk quotas dquot_6.6.0 [ 0.675012] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.677578] *** VALIDATE ramfs *** [ 0.678929] *** VALIDATE hugetlbfs *** [ 0.680509] pnp: PnP ACPI init [ 0.683097] pnp: PnP ACPI: found 6 devices [ 0.706294] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.711282] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.713637] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.718682] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.721313] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.723845] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.726943] NET: Registered protocol family 2 [ 0.729699] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.734689] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.738553] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.745032] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.748807] TCP: Hash tables configured (established 65536 bind 65536) [ 0.752343] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.756244] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.759777] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.763291] NET: Registered protocol family 1 [ 0.766297] RPC: Registered named UNIX socket transport module. [ 0.768672] RPC: Registered udp transport module. [ 0.770477] RPC: Registered tcp transport module. [ 0.772282] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.774791] NET: Registered protocol family 44 [ 0.776534] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.778803] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.780935] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.783273] PCI: CLS 0 bytes, default 64 [ 0.784499] Unpacking initramfs... [ 2.224894] debug: unmapping init [mem 0xffff96017cc64000-0xffff96017ffcffff] [ 2.229165] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.231747] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.235031] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.744613] Initialise system trusted keyrings [ 2.746473] Key type blacklist registered [ 2.748701] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.758230] zbud: loaded [ 2.761873] *** VALIDATE nfs *** [ 2.763059] *** VALIDATE nfs4 *** [ 2.764332] pstore: using deflate compression [ 2.768261] Platform Keyring initialized [ 2.879199] NET: Registered protocol family 38 [ 2.881428] Key type asymmetric registered [ 2.883287] Asymmetric key parser 'x509' registered [ 2.885433] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.888991] io scheduler mq-deadline registered [ 2.891143] io scheduler kyber registered [ 2.893152] io scheduler bfq registered [ 2.895182] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.898694] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.901855] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.905159] ACPI: Power Button [PWRF] [ 2.912625] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.919212] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.938636] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.969190] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.007956] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.012740] Non-volatile memory driver v1.3 [ 3.014691] Linux agpgart interface v0.103 [ 3.047352] virtio_blk virtio1: [vda] 145896 512-byte logical blocks (74.7 MB/71.2 MiB) [ 3.050395] vda: detected capacity change from 0 to 74698752 [ 3.069111] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.071687] vdb: detected capacity change from 0 to 1073741824 [ 3.080073] libphy: Fixed MDIO Bus: probed [ 3.088307] usbcore: registered new interface driver usbserial_generic [ 3.090413] usbserial: USB Serial support registered for generic [ 3.092471] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.096624] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.098542] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.101047] mousedev: PS/2 mouse device common for all mice [ 3.104658] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.108436] rtc_cmos 00:05: RTC can wake from S4 [ 3.112343] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.112649] rtc_cmos 00:05: registered as rtc0 [ 3.117459] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.119065] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.120136] intel_pstate: CPU model not supported [ 3.126161] hid: raw HID events driver (C) Jiri Kosina [ 3.128415] usbcore: registered new interface driver usbhid [ 3.130256] usbhid: USB HID core driver [ 3.131695] drop_monitor: Initializing network drop monitor service [ 3.133824] Initializing XFRM netlink socket [ 3.135839] NET: Registered protocol family 10 [ 3.139111] Segment Routing with IPv6 [ 3.140597] NET: Registered protocol family 17 [ 3.142356] mpls_gso: MPLS GSO support [ 3.147799] RAS: Correctable Errors collector initialized. [ 3.150109] AVX version of gcm_enc/dec engaged. [ 3.151991] AES CTR mode by8 optimization enabled [ 3.236413] sched_clock: Marking stable (3236384099, 0)->(4220319273, -983935174) [ 3.239963] registered taskstats version 1 [ 3.242778] Loading compiled-in X.509 certificates [ 3.245551] zswap: loaded using pool lzo/zbud [ 3.274966] Key type big_key registered [ 3.288817] Key type encrypted registered [ 3.290826] ima: No TPM chip found, activating TPM-bypass! [ 3.292778] ima: Allocated hash algorithm: sha1 [ 3.294838] ima: No architecture policies found [ 3.296790] evm: Initialising EVM extended attributes: [ 3.298912] evm: security.selinux [ 3.300314] evm: security.ima [ 3.301570] evm: security.capability [ 3.303128] evm: HMAC attrs: 0x1 [ 3.306173] rtc_cmos 00:05: setting system clock to 2026-09-03 13:05:18 UTC (1788440718) [ 3.312676] debug: unmapping init [mem 0xffffffff99e03000-0xffffffff99ffffff] [ 3.315539] debug: unmapping init [mem 0xffffffff98b82000-0xffffffff98e58fff] [ 3.327145] Write protecting the kernel read-only data: 28672k [ 3.330820] debug: unmapping init [mem 0xffffffff97203000-0xffffffff973fffff] [ 3.334108] debug: unmapping init [mem 0xffffffff97b14000-0xffffffff97bfffff] [ 3.368064] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 3.376822] systemd[1]: Detected virtualization kvm. [ 3.378915] systemd[1]: Detected architecture x86-64. [ 3.380890] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.406206] systemd[1]: No hostname configured. [ 3.408217] systemd[1]: Set hostname to . [ 3.410350] random: systemd: uninitialized urandom read (16 bytes read) [ 3.413225] systemd[1]: Initializing machine ID from random generator. [ 3.452195] random: ln: uninitialized urandom read (6 bytes read) [ 3.544241] random: systemd: uninitialized urandom read (16 bytes read) [ 3.547714] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 3.552837] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 3.558444] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Slices. [ OK ] Listening on Journal Socket. Starting Setup Virtual Console... Starting Apply Kernel Variables... [ OK ] Reached target Paths. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Reached target Sockets. [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Started Memstrack Anylazing Service. [ OK ] Reached target Swap. [ OK ] Reached target Timers. [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.158898] device-mapper: uevent: version 1.0.3 [ 4.161865] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 4.887086] virtio_net virtio0 ens2: renamed from eth0 [ 4.891692] random: fast init done [ 4.950919] scsi host0: ata_piix [ 4.989613] scsi host1: ata_piix [ 4.991356] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.993700] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.538966] dracut-initqueue[593]: RTNETLINK answers: File exists [ 9.817403] random: crng init done [ 9.818586] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 10.128991] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ 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 dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.302653] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.605362] SELinux: Disabled at runtime. [ 11.666562] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 11.675476] systemd[1]: Detected virtualization kvm. [ 11.677372] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.182287] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.185951] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.189967] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.194344] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.197607] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.205108] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.209600] systemd[1]: Reached target rpc_pipefs.target. [ OK ] Reached target rpc_pipefs.target. Mounting Kernel Debug File System... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Created slice User and Session Slice. Starting Apply Kernel Variables... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Reached target Slices. [ OK ] Listening on udev Kernel Socket. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on Process Core Dump Socket. Mounting Huge Pages File System... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Created slice system-getty.slice. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... Starting Remount Root and Kernel File Systems... [ OK ] Started Dispatch Password Requests to Console Directory Watch. Mounting POSIX Message Queue File System... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Stopped target Initrd Root File System. [ OK ] Created slice system-sshd\x2dkeygen.slice. Activating swap /dev/disk/by-label/SWAP... [ OK ] Started Journal Service. [ 12.406779] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Mounted Kernel Debug File System. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted Huge Pages File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 12.646088] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 12.964214] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 12.985518] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.090231] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.106807] EDAC sbridge: Ver: 1.1.2 [ 14.261121] Key type dns_resolver registered [ 14.566961] NFS: Registering the id_resolver key type [ 14.568570] Key type id_resolver registered [ 14.570119] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Reached target Basic System. [ OK ] Started D-Bus System Message Bus. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Login Service... Starting Network Manager... Starting Restore /run/initramfs on shutdown... [ OK ] Reached target sshd-keygen.target. [ OK ] Started irqbalance daemon. [ OK ] Started Login Service. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting Network Manager Wait Online... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg403-client login: [ 45.449848] libcfs: loading out-of-tree module taints kernel. [ 45.532270] Key type ._llcrypt registered [ 45.539940] Key type .llcrypt registered [ 45.780936] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 45.789288] alg: No test for adler32 (adler32-zlib) [ 46.843654] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [ 47.214607] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 48.863173] Key type lgssc registered [ 51.257246] Lustre: Echo OBD driver; http://www.lustre.org/ [ 79.744921] hrtimer: interrupt took 4909232 ns [ 236.644201] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 241.650364] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 256.333104] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing check_logdir /tmp/testlogs/ [ 262.113836] Lustre: lustre-OST0000-osc-ffff9601c438f000: disconnect after 23s idle [ 262.461593] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing yml_node [ 268.810819] Lustre: DEBUG MARKER: Client: 2.17.56.51 [ 272.565796] Lustre: DEBUG MARKER: MDS: 2.17.56.51 [ 276.343093] Lustre: DEBUG MARKER: OSS: 2.17.56.51 [ 278.226398] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Thu Sep 3 09:09:51 EDT 2026 [ 298.741798] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 300.756827] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 303.742793] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 314.091321] Lustre: Unmounted lustre-client [ 435.376549] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 441.748042] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 466.402966] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 507.079219] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 09:13:40 (1788441220) [ 510.463836] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 513.586457] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 09:13:46 (1788441226) [ 552.856627] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 560.843602] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 586.387610] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 587.170334] LustreError: 2402:0:(lmv_obd.c:211:lmv_notify()) activation of lustre-MDT0001_UUID failed: -22 [ 597.575702] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 603.008369] Lustre: Unmounted lustre-client [ 635.454489] Key type lgssc unregistered [ 635.782754] LNet: 15052:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 635.795571] LNetError: 15052:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 635.818506] LNet: Removed LNI 192.168.204.3@tcp [ 637.010184] Key type .llcrypt unregistered [ 637.013863] Key type ._llcrypt unregistered [ 655.797725] Key type ._llcrypt registered [ 655.802274] Key type .llcrypt registered [ 657.343130] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 09:16:10 (1788441370) [ 659.519780] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 659.571106] alg: No test for adler32 (adler32-zlib) [ 660.599354] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [ 660.917433] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 662.663607] Key type lgssc registered [ 664.384826] Lustre: Echo OBD driver; http://www.lustre.org/ [ 751.996491] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 847.587982] Lustre: Unmounted lustre-client [ 879.966734] Key type lgssc unregistered [ 880.325304] LNet: 18877:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 880.332889] LNetError: 18877:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 880.352650] LNet: Removed LNI 192.168.204.3@tcp [ 881.135772] Key type .llcrypt unregistered [ 881.138155] Key type ._llcrypt unregistered [ 893.682805] Key type ._llcrypt registered [ 893.689130] Key type .llcrypt registered [ 895.485387] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 09:20:08 (1788441608) [ 898.571636] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 898.609603] alg: No test for adler32 (adler32-zlib) [ 899.741295] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [ 900.083744] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 901.799815] Key type lgssc registered [ 903.221780] Lustre: Echo OBD driver; http://www.lustre.org/ [ 957.665312] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 964.488509] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 987.468749] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 990.247066] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 1002.857362] Lustre: Unmounted lustre-client [ 1042.284265] Key type lgssc unregistered [ 1042.744099] LNet: 22491:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1042.757107] LNetError: 22491:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1042.806845] LNet: Removed LNI 192.168.204.3@tcp [ 1043.793099] Key type .llcrypt unregistered [ 1043.798641] Key type ._llcrypt unregistered [ 1058.745432] Key type ._llcrypt registered [ 1058.750702] Key type .llcrypt registered [ 1060.803787] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 09:22:53 (1788441773) [ 1062.818483] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1062.826513] alg: No test for adler32 (adler32-zlib) [ 1063.889424] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [ 1064.056469] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 1065.687324] Key type lgssc registered [ 1067.263456] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1119.948185] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1126.919730] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1148.856594] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1151.872227] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 1158.080903] Lustre: Unmounted lustre-client [ 1187.196552] Key type lgssc unregistered [ 1187.528914] LNet: 25685:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1187.545897] LNetError: 25685:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1187.571814] LNet: Removed LNI 192.168.204.3@tcp [ 1188.374342] Key type .llcrypt unregistered [ 1188.378442] Key type ._llcrypt unregistered [ 1205.976459] Key type ._llcrypt registered [ 1205.982089] Key type .llcrypt registered [ 1207.989536] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 09:25:20 (1788441920) [ 1210.740113] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1210.776877] alg: No test for adler32 (adler32-zlib) [ 1212.024579] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [ 1212.382349] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 1214.184234] Key type lgssc registered [ 1215.736429] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1274.202152] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1280.767672] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1301.933754] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1304.636701] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 1314.802076] Lustre: lustre-MDT0000-mdc-ffff9601c3577800: Connection to lustre-MDT0000 (at 192.168.204.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1325.037626] Lustre: lustre-MDT0001-mdc-ffff9601c3577800: Connection to lustre-MDT0001 (at 192.168.204.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1336.291646] Lustre: 26645:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788442035/real 1788442035] req@0000000057c4ea08 x1875317282052224/t0(0) o400->MGC192.168.204.103@tcp@192.168.204.103@tcp:26/25 lens 224/224 e 0 to 1 dl 1788442051 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1336.313816] LustreError: MGC192.168.204.103@tcp: Connection to MGS (at 192.168.204.103@tcp) was lost; in progress operations using this service will fail [ 1338.350985] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1348.747924] Lustre: Unmounted lustre-client [ 1363.383464] Key type lgssc unregistered [ 1363.772306] LNet: 29351:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1363.783983] LNetError: 29351:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1363.808686] LNet: Removed LNI 192.168.204.3@tcp [ 1364.696778] Key type .llcrypt unregistered [ 1364.704788] Key type ._llcrypt unregistered [ 1380.630663] Key type ._llcrypt registered [ 1380.638350] Key type .llcrypt registered [ 1382.290554] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 09:28:15 (1788442095) [ 1385.171732] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1385.220714] alg: No test for adler32 (adler32-zlib) [ 1386.253615] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [ 1386.479193] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 1388.159258] Key type lgssc registered [ 1389.521629] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1446.279840] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1453.401930] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1478.087084] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1481.142521] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 1486.341509] Lustre: lustre-OST0000-osc-ffff9601c438b800: Connection to lustre-OST0000 (at 192.168.204.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1493.947862] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1504.369335] Lustre: Unmounted lustre-client [ 1535.016709] Key type lgssc unregistered [ 1535.329883] LNet: 32965:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1535.337690] LNetError: 32965:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1535.363409] LNet: Removed LNI 192.168.204.3@tcp [ 1536.327789] Key type .llcrypt unregistered [ 1536.330557] Key type ._llcrypt unregistered [ 1552.909170] Key type ._llcrypt registered [ 1552.914215] Key type .llcrypt registered [ 1554.508562] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 09:31:07 (1788442267) [ 1557.477936] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1557.513803] alg: No test for adler32 (adler32-zlib) [ 1558.617837] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [ 1558.888974] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 1560.535209] Key type lgssc registered [ 1561.976584] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1621.983451] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1628.549802] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1653.056246] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1664.039061] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 1667.833192] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1673.081439] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1685.411808] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-09-03 9h33m18s [ 1688.006344] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1694.700892] Lustre: lustre-MDT0000-mdc-ffff9601c9c45000: Connection to lustre-MDT0000 (at 192.168.204.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1699.511104] LustreError: 36756:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff9601c9c45000: can't stat MDS #0: rc = -19 [ 1701.149247] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1713.129101] LustreError: MGC192.168.204.103@tcp: Connection to MGS (at 192.168.204.103@tcp) was lost; in progress operations using this service will fail [ 1713.154603] Lustre: Evicted from MGS (at 192.168.204.103@tcp) after server handle changed from 0x52e96f3127d05360 to 0x52e96f3127d05703 [ 1713.176935] Lustre: MGC192.168.204.103@tcp: Connection restored to 192.168.204.103@tcp (at 192.168.204.103@tcp) [ 1714.216193] LustreError: lustre-MDT0000-mdc-ffff9601c9c45000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1714.239376] Lustre: lustre-MDT0000-mdc-ffff9601c9c45000: Connection restored to 192.168.204.103@tcp (at 192.168.204.103@tcp) [ 1719.333169] Lustre: DEBUG MARKER: Wait for df (36756) ... [ 1721.175740] Lustre: DEBUG MARKER: done [ 1724.286757] Lustre: Unmounted lustre-client [ 1761.463193] Key type lgssc unregistered [ 1761.886136] LNet: 37776:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1761.900032] LNetError: 37776:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1761.927161] LNet: Removed LNI 192.168.204.3@tcp [ 1762.807346] Key type .llcrypt unregistered [ 1762.813668] Key type ._llcrypt unregistered [ 1796.765917] Key type ._llcrypt registered [ 1796.767352] Key type .llcrypt registered [ 1798.054912] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 09:35:11 (1788442511) [ 1801.113781] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1801.158938] alg: No test for adler32 (adler32-zlib) [ 1802.174460] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [ 1802.436393] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 1804.138069] Key type lgssc registered [ 1805.670402] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1861.383209] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1868.315590] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1891.890143] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1893.743228] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 1898.949680] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-09-03 9h36m52s [ 1900.814192] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1908.955529] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1911.638940] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1931.743970] Lustre: 41919:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788442626/real 1788442626] req@00000000aaef8585 x1875317900712576/t0(0) o101->lustre-MDT0000-mdc-ffff9601c659e800@192.168.204.103@tcp:12/10 lens 576/1152 e 0 to 1 dl 1788442646 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1931.781894] Lustre: lustre-MDT0000-mdc-ffff9601c659e800: Connection to lustre-MDT0000 (at 192.168.204.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1931.825016] Lustre: lustre-MDT0000-mdc-ffff9601c659e800: Connection restored to 192.168.204.103@tcp (at 192.168.204.103@tcp) [ 1953.624736] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1956.877747] Lustre: DEBUG MARKER: done [ 1960.697189] Lustre: Unmounted lustre-client [ 1999.427936] Key type lgssc unregistered [ 1999.822957] LNet: 42810:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1999.840097] LNetError: 42810:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1999.861516] LNet: Removed LNI 192.168.204.3@tcp [ 2000.845690] Key type .llcrypt unregistered [ 2000.848973] Key type ._llcrypt unregistered [ 2034.773135] Key type ._llcrypt registered [ 2034.778054] Key type .llcrypt registered [ 2036.341110] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 09:39:09 (1788442749) [ 2038.234952] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 2040.402259] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 09:39:13 (1788442753) [ 2045.232463] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2045.265402] alg: No test for adler32 (adler32-zlib) [ 2046.527771] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [ 2046.877827] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 2048.599282] Key type lgssc registered [ 2050.083557] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2083.537785] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 09:39:57 (1788442797) [ 2123.441849] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2130.350913] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2154.347766] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2156.222693] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 2160.212800] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 2164.922281] Lustre: Unmounted lustre-client [ 2183.270378] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 2237.481439] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2244.846777] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2245.706990] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 2250.145767] Lustre: Unmounted lustre-client [ 2318.446724] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2325.749850] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2326.397772] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 2331.854181] Lustre: Unmounted lustre-client [ 2358.281519] Lustre: DEBUG MARKER: files compared the same [ 2383.530613] Key type lgssc unregistered [ 2383.883939] LNet: 50440:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2383.895297] LNetError: 50440:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2383.920623] LNet: Removed LNI 192.168.204.3@tcp [ 2384.922119] Key type .llcrypt unregistered [ 2384.928705] Key type ._llcrypt unregistered [ 2404.163238] Key type ._llcrypt registered [ 2404.167693] Key type .llcrypt registered [ 2406.039622] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 09:45:19 (1788443119) [ 2408.747361] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2408.891836] alg: No test for adler32 (adler32-zlib) [ 2410.175544] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [ 2410.520219] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 2412.263139] Key type lgssc registered [ 2413.677563] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2477.899779] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2485.717901] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2511.820364] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2519.087714] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 2523.738041] Lustre: Unmounted lustre-client [ 2556.713066] Key type lgssc unregistered [ 2556.936885] LNet: 54030:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2556.945468] LNetError: 54030:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2556.971610] LNet: Removed LNI 192.168.204.3@tcp [ 2557.930472] Key type .llcrypt unregistered [ 2557.934191] Key type ._llcrypt unregistered [ 2576.070158] Key type ._llcrypt registered [ 2576.072267] Key type .llcrypt registered [ 2577.912827] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 09:48:11 (1788443291) [ 2580.592390] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2580.667360] alg: No test for adler32 (adler32-zlib) [ 2581.778222] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [ 2582.142389] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 2583.832506] Key type lgssc registered [ 2585.650161] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2677.181509] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2717.618745] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2725.679284] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2756.105858] Key type lgssc unregistered [ 2756.387243] LNet: 57524:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2756.396504] LNetError: 57524:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2756.415508] LNet: Removed LNI 192.168.204.3@tcp [ 2757.122638] Key type .llcrypt unregistered [ 2757.127059] Key type ._llcrypt unregistered [ 2773.824481] Key type ._llcrypt registered [ 2773.838813] Key type .llcrypt registered [ 2776.117831] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 09:51:28 (1788443488) [ 2780.581399] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2780.663299] alg: No test for adler32 (adler32-zlib) [ 2781.788639] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [ 2782.017742] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 2783.711832] Key type lgssc registered [ 2785.088739] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2868.387769] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 2876.457249] Lustre: Unmounted lustre-client [ 2917.869615] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 09:53:51 (1788443631) [ 3049.880299] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3072.595444] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 3080.292703] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3080.315676] LustreError: 63997:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff9601c7a46800: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 3080.354831] Lustre: 58507:0:(llite_lib.c:4340:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.204.103@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 3090.646753] Lustre: Unmounted lustre-client [ 3148.385593] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 09:57:42 (1788443862) [ 3162.137496] Key type lgssc unregistered [ 3162.478558] LNet: 65213:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3162.491950] LNetError: 65213:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3162.530405] LNet: Removed LNI 192.168.204.3@tcp [ 3163.350392] Key type .llcrypt unregistered [ 3163.358841] Key type ._llcrypt unregistered [ 3178.932191] Key type ._llcrypt registered [ 3178.934279] Key type .llcrypt registered [ 3179.796135] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3179.809344] alg: No test for adler32 (adler32-zlib) [ 3180.910795] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [ 3181.326728] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 3183.120153] Key type lgssc registered [ 3184.652052] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3246.539723] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3254.081054] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3280.394433] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3287.690423] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 3291.948021] Lustre: Unmounted lustre-client [ 3321.975446] Key type lgssc unregistered [ 3322.221511] LNet: 68236:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3322.238086] LNetError: 68236:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3322.260857] LNet: Removed LNI 192.168.204.3@tcp [ 3322.917898] Key type .llcrypt unregistered [ 3322.922867] Key type ._llcrypt unregistered [ 3335.736986] Key type ._llcrypt registered [ 3335.739845] Key type .llcrypt registered [ 3336.178795] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3336.199802] alg: No test for adler32 (adler32-zlib) [ 3337.403813] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [ 3337.814947] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 3339.503464] Key type lgssc registered [ 3340.974544] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3459.218993] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3465.712951] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3490.800812] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3492.491710] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 3495.542582] Lustre: Unmounted lustre-client [ 3523.118586] Key type lgssc unregistered [ 3523.379702] LNet: 71688:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3523.387762] LNetError: 71688:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3523.402945] LNet: Removed LNI 192.168.204.3@tcp [ 3524.249340] Key type .llcrypt unregistered [ 3524.253791] Key type ._llcrypt unregistered [ 3543.433560] Key type ._llcrypt registered [ 3543.436747] Key type .llcrypt registered [ 3544.969697] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 10:04:18 (1788444258) [ 3547.718956] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3547.769265] alg: No test for adler32 (adler32-zlib) [ 3548.922870] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [ 3549.214866] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 3550.935636] Key type lgssc registered [ 3552.134905] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3613.258982] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3620.983236] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3646.194717] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3653.698404] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 3662.916126] Lustre: Unmounted lustre-client [ 3663.299995] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 3666.686064] Lustre: Unmounted lustre-client [ 3698.051459] Key type lgssc unregistered [ 3698.343771] LNet: 75383:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3698.368070] LNetError: 75383:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3698.381450] LNet: Removed LNI 192.168.204.3@tcp [ 3699.216527] Key type .llcrypt unregistered [ 3699.219130] Key type ._llcrypt unregistered [ 3714.243170] Key type ._llcrypt registered [ 3714.245734] Key type .llcrypt registered [ 3714.653743] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3714.769232] alg: No test for adler32 (adler32-zlib) [ 3716.009860] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [ 3716.201989] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 3717.879364] Key type lgssc registered [ 3719.330976] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3776.220801] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3783.415146] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3806.552443] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3808.675521] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 3813.527814] Lustre: Unmounted lustre-client [ 3845.437702] Key type lgssc unregistered [ 3845.848807] LNet: 78418:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3845.857661] LNetError: 78418:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3845.883340] LNet: Removed LNI 192.168.204.3@tcp [ 3846.804774] Key type .llcrypt unregistered [ 3846.815360] Key type ._llcrypt unregistered [ 3865.694869] Key type ._llcrypt registered [ 3865.698367] Key type .llcrypt registered [ 3867.283188] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 10:09:40 (1788444580) [ 3870.100224] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3870.195478] alg: No test for adler32 (adler32-zlib) [ 3871.498456] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [ 3871.882241] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 3873.688104] Key type lgssc registered [ 3875.348627] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3934.022821] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3940.391309] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3963.683252] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3974.757484] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 3990.102245] Lustre: lustre: nosquash_nids is cleared [ 3990.137158] Lustre: lustre: root_squash is set to 500:500 [ 4013.640435] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.204.3@tcp 192.168.0.[2,10]@tcp [ 4026.405749] Lustre: lustre: nosquash_nids set to 192.168.204.3/24@tcp [ 4030.498516] Lustre: Unmounted lustre-client [ 4060.678992] Key type lgssc unregistered [ 4061.010567] LNet: 82418:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4061.019481] LNetError: 82418:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4061.041751] LNet: Removed LNI 192.168.204.3@tcp [ 4061.885872] Key type .llcrypt unregistered [ 4061.889446] Key type ._llcrypt unregistered [ 4080.821637] Key type ._llcrypt registered [ 4080.828276] Key type .llcrypt registered [ 4082.429135] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 10:13:15 (1788444795) [ 4084.877357] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 4087.005792] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 10:13:20 (1788444800) [ 4090.230376] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4090.246512] alg: No test for adler32 (adler32-zlib) [ 4091.562701] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [ 4091.882672] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 4093.543982] Key type lgssc registered [ 4094.705381] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4155.009747] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4162.185590] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4186.931945] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4188.166729] Lustre: lustre: root_squash is set to 500:500 [ 4188.222726] Lustre: lustre: nosquash_nids set to 192.168.204.3/24@tcp [ 4198.501580] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 4205.344923] Lustre: Unmounted lustre-client [ 4238.531177] Key type lgssc unregistered [ 4238.798538] LNet: 86384:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4238.806745] LNetError: 86384:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4238.839859] LNet: Removed LNI 192.168.204.3@tcp [ 4239.575250] Key type .llcrypt unregistered [ 4239.580250] Key type ._llcrypt unregistered [ 4262.847608] Key type ._llcrypt registered [ 4262.850872] Key type .llcrypt registered [ 4264.716850] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 4266.970521] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 10:16:20 (1788444980) [ 4288.416958] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4288.480058] alg: No test for adler32 (adler32-zlib) [ 4289.609945] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [ 4289.944042] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 4291.697830] Key type lgssc registered [ 4293.401196] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4392.883198] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4399.923534] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4427.356288] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4509.980158] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4519.083198] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4544.227909] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4558.342295] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 4592.607624] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9601c5d78800.ost_server_uuid 50 [ 4594.708081] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9601c5d78800.ost_server_uuid in IDLE state after 0 sec [ 4595.485695] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 4599.919590] Lustre: Unmounted lustre-client [ 4602.608166] Lustre: Unmounted lustre-client [ 4658.351972] Key type lgssc unregistered [ 4658.668079] LNet: 94061:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4658.672534] LNetError: 94061:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4658.703463] LNet: Removed LNI 192.168.204.3@tcp [ 4659.800380] Key type .llcrypt unregistered [ 4659.802392] Key type ._llcrypt unregistered [ 4697.896641] Key type ._llcrypt registered [ 4697.901750] Key type .llcrypt registered [ 4699.612509] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 10:23:32 (1788445412) [ 4720.604421] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4720.652086] alg: No test for adler32 (adler32-zlib) [ 4721.920580] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [ 4722.353137] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 4724.271337] Key type lgssc registered [ 4726.185065] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4825.242333] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4833.112955] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4859.237423] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4861.029807] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 4882.399637] Lustre: 95427:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788445581/real 1788445581] req@00000000543b04ae x1875320962552960/t0(0) o400->lustre-OST0000-osc-ffff9601c438b000@192.168.204.103@tcp:28/4 lens 224/224 e 0 to 1 dl 1788445597 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4882.426955] Lustre: lustre-OST0000-osc-ffff9601c438b000: Connection to lustre-OST0000 (at 192.168.204.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4887.137125] Lustre: 95427:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788445586/real 1788445586] req@000000003d288c4c x1875320962553472/t0(0) o400->lustre-OST0000-osc-ffff9601c438b000@192.168.204.103@tcp:28/4 lens 224/224 e 0 to 1 dl 1788445602 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4892.643350] Lustre: 95427:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788445591/real 1788445591] req@00000000c729c49d x1875320962553984/t0(0) o400->lustre-OST0000-osc-ffff9601c438b000@192.168.204.103@tcp:28/4 lens 224/224 e 0 to 1 dl 1788445607 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4892.717249] Lustre: lustre-OST0000-osc-ffff9601c438b000: Connection restored to 192.168.204.103@tcp (at 192.168.204.103@tcp) [ 4897.788202] Lustre: lustre-MDT0000-mdc-ffff9601c438b000: Connection to lustre-MDT0000 (at 192.168.204.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4897.800896] Lustre: 95427:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788445596/real 1788445596] req@00000000d4b43fe8 x1875320962554496/t0(0) o400->lustre-OST0000-osc-ffff9601c438b000@192.168.204.103@tcp:28/4 lens 224/224 e 0 to 1 dl 1788445612 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4912.865019] Lustre: 95426:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788445612/real 1788445612] req@00000000565b0ac3 x1875320962556800/t0(0) o400->MGC192.168.204.103@tcp@192.168.204.103@tcp:26/25 lens 224/224 e 0 to 1 dl 1788445628 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4912.910138] LustreError: MGC192.168.204.103@tcp: Connection to MGS (at 192.168.204.103@tcp) was lost; in progress operations using this service will fail [ 4923.132662] Lustre: Evicted from MGS (at 192.168.204.103@tcp) after server handle changed from 0x3d8504ed3d7ac894 to 0x3d8504ed3d7acb26 [ 4923.150660] Lustre: MGC192.168.204.103@tcp: Connection restored to 192.168.204.103@tcp (at 192.168.204.103@tcp) [ 4927.734191] Lustre: lustre-MDT0000-mdc-ffff9601c438b000: Connection restored to 192.168.204.103@tcp (at 192.168.204.103@tcp) [ 4936.103152] Lustre: Unmounted lustre-client [ 4967.496867] Key type lgssc unregistered [ 4968.096146] LNet: 99734:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4968.105573] LNetError: 99734:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4968.125111] LNet: Removed LNI 192.168.204.3@tcp [ 4969.180189] Key type .llcrypt unregistered [ 4969.186269] Key type ._llcrypt unregistered [ 4988.797551] Key type ._llcrypt registered [ 4988.807798] Key type .llcrypt registered [ 4990.616577] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 10:28:23 (1788445703) [ 5006.576594] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5006.704905] alg: No test for adler32 (adler32-zlib) [ 5007.888909] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [ 5008.367491] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 5010.151416] Key type lgssc registered [ 5011.958700] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5058.897892] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5173.265387] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5179.873500] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5207.120656] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5210.164292] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 5213.415910] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 5215.384625] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 10:32:08 (1788445928) [ 5487.541462] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5497.868105] Lustre: Unmounted lustre-client [ 5626.263647] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5632.365452] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5656.398856] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5699.139418] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 10:40:13 (1788446413) [ 5793.501379] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5799.179515] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5824.680482] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5825.466860] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 5830.987185] Lustre: Unmounted lustre-client [ 5864.588476] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 10:42:58 (1788446578) [ 5951.768928] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5957.626790] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5983.516951] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5984.255420] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 5988.498417] Lustre: Unmounted lustre-client [ 6016.357760] Key type lgssc unregistered [ 6016.679969] LNet: 122756:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6016.683058] LNetError: 122756:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6016.712399] LNet: Removed LNI 192.168.204.3@tcp [ 6017.480385] Key type .llcrypt unregistered [ 6017.482710] Key type ._llcrypt unregistered [ 6033.817543] Key type ._llcrypt registered [ 6033.821203] Key type .llcrypt registered [ 6035.147582] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 10:45:48 (1788446748) [ 6037.850911] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6037.867548] alg: No test for adler32 (adler32-zlib) [ 6039.018753] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [ 6039.232281] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 6040.887276] Key type lgssc registered [ 6042.033542] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6091.584885] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6097.748238] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6119.492603] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6122.143806] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 6138.802917] Lustre: Unmounted lustre-client [ 6166.008847] Key type lgssc unregistered [ 6166.227325] LNet: 126357:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6166.244447] LNetError: 126357:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6166.267585] LNet: Removed LNI 192.168.204.3@tcp [ 6166.897449] Key type .llcrypt unregistered [ 6166.900638] Key type ._llcrypt unregistered [ 6181.649407] Key type ._llcrypt registered [ 6181.651639] Key type .llcrypt registered [ 6182.743546] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 10:48:16 (1788446896) [ 6184.490760] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6184.620527] alg: No test for adler32 (adler32-zlib) [ 6185.634432] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [ 6185.825576] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 6187.471941] Key type lgssc registered [ 6188.433907] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6233.165089] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6239.263625] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6261.853522] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6264.763456] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 6275.044360] Lustre: lustre-OST0000-osc-ffff9601c912c000: Connection to lustre-OST0000 (at 192.168.204.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6285.268704] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff9601c912c000.ost_server_uuid 50 [ 6286.735443] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 6297.801311] Lustre: Unmounted lustre-client [ 6317.904961] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 10:50:31 (1788447031) [ 6349.595143] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6354.731518] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6374.294943] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6399.018586] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6399.617947] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 6404.607811] Lustre: lustre-OST0000-osc-ffff9601c3574000: Connection to lustre-OST0000 (at 192.168.204.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6439.315674] Lustre: Unmounted lustre-client [ 6488.008891] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 10:53:21 (1788447201) [ 6523.450620] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6528.495049] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6549.187971] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6569.573661] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6570.390834] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 6575.594939] Lustre: lustre-OST0000-osc-ffff9601c6b3c800: Connection to lustre-OST0000 (at 192.168.204.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6599.704246] Lustre: Unmounted lustre-client [ 6650.968480] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 10:56:04 (1788447364) [ 6743.514291] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6749.173806] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6776.401554] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6843.121151] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6849.817987] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6869.741685] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6898.906741] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 6943.124105] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6946.476835] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 1 sec [ 6948.756737] Lustre: Unmounted lustre-client [ 6981.654776] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 11:01:35 (1788447695) [ 7012.116700] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7017.883085] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7039.475689] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7072.158800] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7104.467275] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 7144.932874] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7146.523179] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7150.573156] Lustre: lustre-OST0001-osc-ffff9601c3570800: Connection to lustre-OST0001 (at 192.168.204.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7166.216666] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7176.605161] Lustre: Unmounted lustre-client [ 7232.727078] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 11:05:46 (1788447946) [ 7269.755450] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7276.640736] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7300.380120] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7300.953411] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 7324.509136] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7326.243277] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7340.696339] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9601c3438000.ost_server_uuid 50 [ 7341.884352] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9601c3438000.ost_server_uuid in IDLE state after 0 sec [ 7344.760700] Lustre: Unmounted lustre-client [ 7345.169823] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 7345.176464] Lustre: Skipped 3 previous similar messages [ 7345.277438] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 7346.457653] LustreError: 149801:0:(sec.c:403:import_sec_validate_get()) import 00000000dd2d0bd0 (NEW) with no sec [ 7408.962836] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 11:08:42 (1788448122) [ 7450.462363] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7462.052710] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7490.314623] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7512.763113] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7513.325306] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 7513.513315] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 7529.798833] Lustre: Unmounted lustre-client [ 7529.803294] Lustre: Skipped 1 previous similar message [ 7581.018920] Key type lgssc unregistered [ 7581.385962] LNet: 153233:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7581.395531] LNetError: 153233:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7581.417660] LNet: Removed LNI 192.168.204.3@tcp [ 7582.320937] Key type .llcrypt unregistered [ 7582.325327] Key type ._llcrypt unregistered [ 7597.792669] Key type ._llcrypt registered [ 7597.795961] Key type .llcrypt registered [ 7599.572880] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 11:11:52 (1788448312) [ 7600.753511] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7600.831711] alg: No test for adler32 (adler32-zlib) [ 7602.067466] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [ 7602.274653] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 7604.079740] Key type lgssc registered [ 7605.074244] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7662.536158] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7669.200684] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7692.671606] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7715.813719] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7717.492614] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7718.631715] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 7721.744235] LustreError: lustre-MDT0001-mdc-ffff9601dedd6000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 7721.770177] Lustre: lustre-MDT0001-mdc-ffff9601dedd6000: Connection restored to 192.168.204.103@tcp (at 192.168.204.103@tcp) [ 7729.319490] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7730.856979] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7755.271458] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7759.783280] Lustre: Unmounted lustre-client [ 7802.902630] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 11:15:16 (1788448516) [ 7920.866181] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7927.098334] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7952.774514] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7953.549787] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 7984.698572] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7986.290297] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 8006.380170] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 8016.782305] Lustre: Unmounted lustre-client [ 8060.308857] Key type lgssc unregistered [ 8060.567800] LNet: 162853:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8060.577846] LNetError: 162853:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8060.593341] LNet: Removed LNI 192.168.204.3@tcp [ 8061.296309] Key type .llcrypt unregistered [ 8061.300414] Key type ._llcrypt unregistered [ 8094.333230] Key type ._llcrypt registered [ 8094.336595] Key type .llcrypt registered [ 8095.844605] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 11:20:09 (1788448809) [ 8098.916623] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8098.932919] alg: No test for adler32 (adler32-zlib) [ 8099.973530] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [ 8100.191050] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 8101.871231] Key type lgssc registered [ 8103.487119] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8154.150471] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8160.613199] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8184.413787] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8186.997823] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 8240.691258] Lustre: Unmounted lustre-client [ 8281.683826] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8282.601676] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 8320.152920] Lustre: Unmounted lustre-client [ 8350.337334] Key type lgssc unregistered [ 8350.670561] LNet: 167308:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8350.683467] LNetError: 167308:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8350.711954] LNet: Removed LNI 192.168.204.3@tcp [ 8351.390652] Key type .llcrypt unregistered [ 8351.394244] Key type ._llcrypt unregistered [ 8369.325814] Key type ._llcrypt registered [ 8369.330523] Key type .llcrypt registered [ 8371.208559] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 11:24:44 (1788449084) [ 8374.823775] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8374.888130] alg: No test for adler32 (adler32-zlib) [ 8376.323085] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [ 8376.829872] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 8378.647672] Key type lgssc registered [ 8380.521608] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8438.066371] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8444.875711] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8469.725374] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8472.737579] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 8494.129690] Lustre: Unmounted lustre-client [ 8525.752578] Key type lgssc unregistered [ 8526.045336] LNet: 171065:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8526.060357] LNetError: 171065:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8526.084177] LNet: Removed LNI 192.168.204.3@tcp [ 8526.747238] Key type .llcrypt unregistered [ 8526.750066] Key type ._llcrypt unregistered [ 8538.832635] Key type ._llcrypt registered [ 8538.842980] Key type .llcrypt registered [ 8539.369679] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8539.392296] alg: No test for adler32 (adler32-zlib) [ 8540.618302] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [ 8540.983563] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 8542.711622] Key type lgssc registered [ 8544.372164] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8604.433566] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8610.794976] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8634.393389] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8646.710843] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 8655.522178] Lustre: Unmounted lustre-client [ 8683.034886] Key type lgssc unregistered [ 8683.361223] LNet: 174134:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8683.377872] LNetError: 174134:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8683.402226] LNet: Removed LNI 192.168.204.3@tcp [ 8684.425433] Key type .llcrypt unregistered [ 8684.428538] Key type ._llcrypt unregistered [ 8701.050813] Key type ._llcrypt registered [ 8701.053364] Key type .llcrypt registered [ 8702.326867] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 11:30:16 (1788449416) [ 8704.851099] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8704.883369] alg: No test for adler32 (adler32-zlib) [ 8705.990284] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [ 8706.319587] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 8708.039197] Key type lgssc registered [ 8709.520122] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8761.109539] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8766.970962] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8790.381141] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8792.198648] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 8813.491347] Lustre: Unmounted lustre-client [ 8840.782069] Key type lgssc unregistered [ 8840.976149] LNet: 177892:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8840.984819] LNetError: 177892:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8841.004563] LNet: Removed LNI 192.168.204.3@tcp [ 8841.827450] Key type .llcrypt unregistered [ 8841.830503] Key type ._llcrypt unregistered [ 8856.484205] Key type ._llcrypt registered [ 8856.504059] Key type .llcrypt registered [ 8856.778500] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8856.796751] alg: No test for adler32 (adler32-zlib) [ 8857.919410] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [ 8858.312952] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 8859.967749] Key type lgssc registered [ 8861.540503] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8913.274627] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8919.443631] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8942.508632] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8944.224987] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 8959.222106] Lustre: Unmounted lustre-client [ 8989.429813] Key type lgssc unregistered [ 8989.725966] LNet: 180957:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8989.739062] LNetError: 180957:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8989.760875] LNet: Removed LNI 192.168.204.3@tcp [ 8990.691288] Key type .llcrypt unregistered [ 8990.696304] Key type ._llcrypt unregistered [ 9007.074647] Key type ._llcrypt registered [ 9007.076571] Key type .llcrypt registered [ 9008.130674] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 11:35:22 (1788449722) [ 9030.004458] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9030.040640] alg: No test for adler32 (adler32-zlib) [ 9031.080668] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [ 9031.319712] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 9032.991260] Key type lgssc registered [ 9033.942767] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9118.882681] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9124.500753] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9145.914448] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9186.597908] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 11:38:20 (1788449900) [ 9219.047205] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9224.368994] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9246.289877] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9248.061498] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 9253.021291] Lustre: Unmounted lustre-client [ 9288.846765] Key type lgssc unregistered [ 9289.051897] LNet: 188059:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9289.061834] LNetError: 188059:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9289.080234] LNet: Removed LNI 192.168.204.3@tcp [ 9289.833869] Key type .llcrypt unregistered [ 9289.836404] Key type ._llcrypt unregistered [ 9307.373333] Key type ._llcrypt registered [ 9307.382237] Key type .llcrypt registered [ 9308.635201] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 9310.625351] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 11:40:24 (1788450024) [ 9330.663875] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9330.676546] alg: No test for adler32 (adler32-zlib) [ 9331.741340] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [ 9332.029491] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 9333.847234] Key type lgssc registered [ 9335.135134] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9434.450294] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9440.670337] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9468.291795] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9494.215995] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9496.859301] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [ 9524.587792] Lustre: Unmounted lustre-client [ 9666.840634] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9672.633835] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9698.610509] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9739.701229] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 11:47:33 (1788450453) [ 9741.410599] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 9743.323512] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 11:47:37 (1788450457) [ 9846.615845] Key type lgssc unregistered [ 9846.882298] LNet: 199509:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9846.889149] LNetError: 199509:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9846.911470] LNet: Removed LNI 192.168.204.3@tcp [ 9847.527326] Key type .llcrypt unregistered [ 9847.530139] Key type ._llcrypt unregistered [ 9865.546214] Key type ._llcrypt registered [ 9865.549850] Key type .llcrypt registered [ 9867.258389] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 11:49:40 (1788450580) [ 9913.126457] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9913.152478] alg: No test for adler32 (adler32-zlib) [ 9914.305397] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [ 9914.617933] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [ 9916.311336] Key type lgssc registered [ 9917.495584] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9967.565340] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9974.578523] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10013.196805] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10043.018400] Key type lgssc unregistered [10043.347783] LNet: 203246:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10043.355562] LNetError: 203246:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10043.383726] LNet: Removed LNI 192.168.204.3@tcp [10044.185756] Key type .llcrypt unregistered [10044.187771] Key type ._llcrypt unregistered [10061.112466] Key type ._llcrypt registered [10061.117527] Key type .llcrypt registered [10062.811820] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 11:52:56 (1788450776) [10066.523577] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10066.572048] alg: No test for adler32 (adler32-zlib) [10067.689543] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [10068.022940] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [10069.727291] Key type lgssc registered [10070.776086] Lustre: Echo OBD driver; http://www.lustre.org/ [10116.515108] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10122.725766] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10149.129588] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10150.993291] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [10164.233056] Lustre: lustre-MDT0000-mdc-ffff9601c912e000: Connection to lustre-MDT0000 (at 192.168.204.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [10174.443959] Lustre: lustre-MDT0001-mdc-ffff9601c912e000: Connection to lustre-MDT0001 (at 192.168.204.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [10190.568682] Lustre: 204239:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788450889/real 1788450889] req@0000000080a6412a x1875326568317824/t0(0) o400->MGC192.168.204.103@tcp@192.168.204.103@tcp:26/25 lens 224/224 e 0 to 1 dl 1788450905 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10190.597435] LustreError: MGC192.168.204.103@tcp: Connection to MGS (at 192.168.204.103@tcp) was lost; in progress operations using this service will fail [10199.854834] Lustre: Evicted from MGS (at 192.168.204.103@tcp) after server handle changed from 0x3e707ae52cd716a0 to 0x3e707ae52cd732a0 [10199.874658] Lustre: MGC192.168.204.103@tcp: Connection restored to 192.168.204.103@tcp (at 192.168.204.103@tcp) [10219.521673] LustreError: lustre-MDT0000-mdc-ffff9601c912e000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [10219.598393] Lustre: lustre-MDT0000-mdc-ffff9601c912e000: Connection restored to 192.168.204.103@tcp (at 192.168.204.103@tcp) [10225.663324] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10227.267959] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10233.053877] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10234.598438] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [10237.632396] Lustre: Unmounted lustre-client [10282.912965] Key type lgssc unregistered [10283.238872] LNet: 208196:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10283.255904] LNetError: 208196:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10283.283646] LNet: Removed LNI 192.168.204.3@tcp [10284.363194] Key type .llcrypt unregistered [10284.370328] Key type ._llcrypt unregistered [10304.713496] Key type ._llcrypt registered [10304.717091] Key type .llcrypt registered [10306.327274] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 11:56:59 (1788451019) [10310.445582] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10310.465660] alg: No test for adler32 (adler32-zlib) [10311.829974] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [10312.329446] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [10314.096525] Key type lgssc registered [10315.843674] Lustre: Echo OBD driver; http://www.lustre.org/ [10371.284099] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10378.345967] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10435.733612] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10442.447898] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10478.383596] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10507.180147] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10556.943278] Key type lgssc unregistered [10557.349308] LNet: 213511:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10557.363617] LNetError: 213511:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10557.378332] LNet: Removed LNI 192.168.204.3@tcp [10558.162323] Key type .llcrypt unregistered [10558.164917] Key type ._llcrypt unregistered [10603.512947] Key type ._llcrypt registered [10603.516029] Key type .llcrypt registered [10605.026397] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 12:01:58 (1788451318) [10648.563680] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10648.633785] alg: No test for adler32 (adler32-zlib) [10649.876773] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [10650.216857] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [10651.959434] Key type lgssc registered [10653.411319] Lustre: Echo OBD driver; http://www.lustre.org/ [10746.724280] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10753.392104] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10778.844860] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10825.376363] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 12:05:38 (1788451538) [10834.259663] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 12:05:47 (1788451547) [10873.139405] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10879.727664] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10902.450574] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10904.269448] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [10910.994602] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [10913.326763] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [10915.409425] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [10917.944896] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [10925.046959] Lustre: lustre-MDT0000-mdc-ffff9601c5889000: Connection to lustre-MDT0000 (at 192.168.204.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [10941.215166] Lustre: 215406:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788451640/real 1788451640] req@00000000999b2bce x1875327178516608/t0(0) o400->MGC192.168.204.103@tcp@192.168.204.103@tcp:26/25 lens 224/224 e 0 to 1 dl 1788451656 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10941.249504] LustreError: MGC192.168.204.103@tcp: Connection to MGS (at 192.168.204.103@tcp) was lost; in progress operations using this service will fail [10951.685098] Lustre: Evicted from MGS (at 192.168.204.103@tcp) after server handle changed from 0xa52a4bed1e572fc1 to 0xa52a4bed1e573230 [10951.714122] Lustre: MGC192.168.204.103@tcp: Connection restored to 192.168.204.103@tcp (at 192.168.204.103@tcp) [10953.988055] Lustre: lustre-MDT0000-mdc-ffff9601c5889000: Connection restored to 192.168.204.103@tcp (at 192.168.204.103@tcp) [10966.766403] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10968.648644] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10972.656994] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [10977.274455] Lustre: lustre-MDT0000-mdc-ffff9601c5889000: Connection to lustre-MDT0000 (at 192.168.204.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [10993.631369] Lustre: 215407:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788451692/real 1788451692] req@00000000e6089f29 x1875327178525824/t0(0) o400->MGC192.168.204.103@tcp@192.168.204.103@tcp:26/25 lens 224/224 e 0 to 1 dl 1788451708 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10993.652391] LustreError: MGC192.168.204.103@tcp: Connection to MGS (at 192.168.204.103@tcp) was lost; in progress operations using this service will fail [11002.861925] Lustre: Evicted from MGS (at 192.168.204.103@tcp) after server handle changed from 0xa52a4bed1e573230 to 0xa52a4bed1e573444 [11002.884254] Lustre: MGC192.168.204.103@tcp: Connection restored to 192.168.204.103@tcp (at 192.168.204.103@tcp) [11011.296868] Lustre: lustre-MDT0000-mdc-ffff9601c5889000: Connection restored to 192.168.204.103@tcp (at 192.168.204.103@tcp) [11022.531869] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11024.370213] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [11029.417114] Lustre: Unmounted lustre-client [11110.568477] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11117.376895] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11141.849422] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11142.716435] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [11145.131691] Lustre: Unmounted lustre-client [11178.953517] Key type lgssc unregistered [11179.226370] LNet: 225996:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11179.236408] LNetError: 225996:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11179.256566] LNet: Removed LNI 192.168.204.3@tcp [11180.127404] Key type .llcrypt unregistered [11180.133153] Key type ._llcrypt unregistered [11197.291636] Key type ._llcrypt registered [11197.300552] Key type .llcrypt registered [11199.057744] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 12:11:52 (1788451912) [11203.047440] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11203.070274] alg: No test for adler32 (adler32-zlib) [11204.247820] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [11204.496286] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [11206.175222] Key type lgssc registered [11207.692943] Lustre: Echo OBD driver; http://www.lustre.org/ [11267.634521] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11274.212532] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11304.540255] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11311.688512] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [11317.601077] Lustre: Unmounted lustre-client [11387.808963] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11394.520461] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11417.083119] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11417.809554] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [11426.730297] Lustre: Unmounted lustre-client [11509.392541] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11515.908332] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11539.316010] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11550.232090] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [11552.994345] Lustre: Unmounted lustre-client [11592.102566] Key type lgssc unregistered [11592.384488] LNet: 233054:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11592.402908] LNetError: 233054:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11592.434581] LNet: Removed LNI 192.168.204.3@tcp [11593.525305] Key type .llcrypt unregistered [11593.528973] Key type ._llcrypt unregistered [11615.504720] Key type ._llcrypt registered [11615.506332] Key type .llcrypt registered [11617.592927] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 12:18:50 (1788452330) [11621.717607] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11621.912359] alg: No test for adler32 (adler32-zlib) [11623.132220] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [11623.447516] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [11625.183820] Key type lgssc registered [11626.728328] Lustre: Echo OBD driver; http://www.lustre.org/ [11676.836693] Key type lgssc unregistered [11677.126456] LNet: 235378:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11677.135846] LNetError: 235378:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11677.151320] LNet: Removed LNI 192.168.204.3@tcp [11677.811864] Key type .llcrypt unregistered [11677.814750] Key type ._llcrypt unregistered [11708.725981] Key type ._llcrypt registered [11708.733052] Key type .llcrypt registered [11709.143819] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11709.162324] alg: No test for adler32 (adler32-zlib) [11710.256096] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [11710.439417] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [11712.088063] Key type lgssc registered [11713.396570] Lustre: Echo OBD driver; http://www.lustre.org/ [11805.034348] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11811.542948] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11835.366980] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11889.711347] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [11891.784423] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 12:23:25 (1788452605) [11931.666374] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11938.933551] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11967.341179] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11997.317543] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11999.481339] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [12004.854298] Lustre: lustre-OST0001-osc-ffff9601cafec000: Connection to lustre-OST0001 (at 192.168.204.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [12013.325795] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12023.709020] Lustre: Unmounted lustre-client [12069.158200] Key type lgssc unregistered [12069.477034] LNet: 242504:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12069.495087] LNetError: 242504:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12069.542605] LNet: Removed LNI 192.168.204.3@tcp [12070.346278] Key type .llcrypt unregistered [12070.353164] Key type ._llcrypt unregistered [12106.492289] Key type ._llcrypt registered [12106.496508] Key type .llcrypt registered [12108.245071] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 12:27:01 (1788452821) [12126.154887] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12126.194412] alg: No test for adler32 (adler32-zlib) [12127.404801] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [12127.709579] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [12129.559197] Key type lgssc registered [12131.032462] Lustre: Echo OBD driver; http://www.lustre.org/ [12187.292406] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12195.296902] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12219.274756] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 12:28:52 (1788452932) [12253.179878] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12259.271280] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12282.823710] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12285.263305] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [12296.724992] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [12300.768388] Lustre: Unmounted lustre-client [12420.518680] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12427.018230] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12485.437373] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12486.416088] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [12489.839817] Lustre: Unmounted lustre-client [12520.443063] Key type lgssc unregistered [12520.795064] LNet: 250173:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12520.798914] LNetError: 250173:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12520.815851] LNet: Removed LNI 192.168.204.3@tcp [12521.693089] Key type .llcrypt unregistered [12521.695602] Key type ._llcrypt unregistered [12558.687647] Key type ._llcrypt registered [12558.690964] Key type .llcrypt registered [12559.084500] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12559.098626] alg: No test for adler32 (adler32-zlib) [12560.258843] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [12560.528310] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [12562.249966] Key type lgssc registered [12563.705720] Lustre: Echo OBD driver; http://www.lustre.org/ [12661.123626] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12669.764097] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12698.727331] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12754.907270] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [12756.872991] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 12:37:50 (1788453470) [12799.056856] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12806.640388] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12834.001089] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12839.177064] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [12842.749590] Lustre: Unmounted lustre-client [12872.499722] Key type lgssc unregistered [12872.927782] LNet: 256817:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12872.944154] LNetError: 256817:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12872.980591] LNet: Removed LNI 192.168.204.3@tcp [12873.968081] Key type .llcrypt unregistered [12873.969659] Key type ._llcrypt unregistered [12894.829801] Key type ._llcrypt registered [12894.838685] Key type .llcrypt registered [12897.181154] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [12899.785875] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 12:40:12 (1788453612) [12913.177418] Key type .llcrypt unregistered [12913.184467] Key type ._llcrypt unregistered [12922.782773] Key type ._llcrypt registered [12922.793756] Key type .llcrypt registered [12923.206629] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12923.267767] alg: No test for adler32 (adler32-zlib) [12924.324948] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [12924.554790] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [12926.327340] Key type lgssc registered [12928.400814] Lustre: Echo OBD driver; http://www.lustre.org/ [12990.999561] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13010.464923] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [13013.315172] Lustre: Unmounted lustre-client [13038.061821] Key type lgssc unregistered [13038.402918] LNet: 260283:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13038.423244] LNetError: 260283:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13038.454539] LNet: Removed LNI 192.168.204.3@tcp [13039.584712] Key type .llcrypt unregistered [13039.587086] Key type ._llcrypt unregistered [13056.619253] Key type ._llcrypt registered [13056.629243] Key type .llcrypt registered [13058.292479] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 12:42:51 (1788453771) [13061.431362] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13061.458390] alg: No test for adler32 (adler32-zlib) [13062.523243] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [13062.804869] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [13064.466655] Key type lgssc registered [13065.603743] Lustre: Echo OBD driver; http://www.lustre.org/ [13150.200308] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13187.338224] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13194.256773] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13197.075784] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [13200.549394] Lustre: Unmounted lustre-client [13239.795865] Key type lgssc unregistered [13240.163994] LNet: 263831:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13240.177174] LNetError: 263831:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13240.201142] LNet: Removed LNI 192.168.204.3@tcp [13241.057070] Key type .llcrypt unregistered [13241.060318] Key type ._llcrypt unregistered [13262.186135] Key type ._llcrypt registered [13262.189752] Key type .llcrypt registered [13264.177299] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 12:46:17 (1788453977) [13267.051968] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13267.162077] alg: No test for adler32 (adler32-zlib) [13268.297171] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [13268.560269] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [13270.297257] Key type lgssc registered [13271.841876] Lustre: Echo OBD driver; http://www.lustre.org/ [13334.153754] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13343.147779] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13372.421972] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13378.688984] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [13383.663702] Lustre: lustre-MDT0000-mdc-ffff9601c40ef000: Connection to lustre-MDT0000 (at 192.168.204.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [13390.087660] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [13390.117449] LustreError: 266992:0:(file.c:6154:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [13390.634642] LustreError: 267011:0:(file.c:6154:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [13390.651760] LustreError: 267011:0:(file.c:6154:ll_inode_revalidate_fini()) Skipped 5 previous similar messages [13390.765644] LustreError: MGC192.168.204.103@tcp: Connection to MGS (at 192.168.204.103@tcp) was lost; in progress operations using this service will fail [13390.859747] Lustre: Unmounted lustre-client [13413.370513] Key type lgssc unregistered [13413.765757] LNet: 267408:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13413.797700] LNetError: 267408:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13413.836614] LNet: Removed LNI 192.168.204.3@tcp [13414.728441] Key type .llcrypt unregistered [13414.735431] Key type ._llcrypt unregistered [13433.879843] Key type ._llcrypt registered [13433.883818] Key type .llcrypt registered [13435.296869] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 12:49:08 (1788454148) [13438.717584] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13438.738940] alg: No test for adler32 (adler32-zlib) [13440.093519] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [13440.566634] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [13442.310906] Key type lgssc registered [13444.431755] Lustre: Echo OBD driver; http://www.lustre.org/ [13503.038309] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13509.078710] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13536.848213] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13539.892742] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [13544.945242] Lustre: lustre-MDT0001-mdc-ffff9601c3577000: Connection to lustre-MDT0001 (at 192.168.204.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [13550.678719] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [13553.317731] Lustre: Unmounted lustre-client [13576.493330] Key type lgssc unregistered [13576.740910] LNet: 270998:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13576.756384] LNetError: 270998:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13576.790770] LNet: Removed LNI 192.168.204.3@tcp [13577.976220] Key type .llcrypt unregistered [13577.977932] Key type ._llcrypt unregistered [13597.464756] Key type ._llcrypt registered [13597.469723] Key type .llcrypt registered [13598.866554] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 12:51:52 (1788454312) [13620.093248] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13620.224669] alg: No test for adler32 (adler32-zlib) [13621.343563] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [13621.603415] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [13623.279587] Key type lgssc registered [13624.577587] Lustre: Echo OBD driver; http://www.lustre.org/ [13744.066202] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13746.845951] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [13794.792696] Lustre: lustre-MDT0001-mdc-ffff9601c5352000: Connection to lustre-MDT0001 (at 192.168.204.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [13804.145103] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [13814.581613] Lustre: Unmounted lustre-client [13843.831083] Key type lgssc unregistered [13844.207870] LNet: 276785:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13844.216229] LNetError: 276785:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13844.234039] LNet: Removed LNI 192.168.204.3@tcp [13845.365104] Key type .llcrypt unregistered [13845.370190] Key type ._llcrypt unregistered [13862.711299] Key type ._llcrypt registered [13862.713688] Key type .llcrypt registered [13864.019919] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 12:56:17 (1788454577) [13866.441164] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [13868.574276] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 12:56:22 (1788454582) [13870.817305] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [13873.060714] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 12:56:26 (1788454586) [13875.161482] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [13877.718358] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 12:56:30 (1788454590) [13879.626862] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [13881.874181] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 12:56:35 (1788454595) [13884.396401] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [13886.906978] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 12:56:40 (1788454600) [13899.020598] Key type .llcrypt unregistered [13899.025127] Key type ._llcrypt unregistered [13909.087865] Key type ._llcrypt registered [13909.092636] Key type .llcrypt registered [13909.900212] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13909.940311] alg: No test for adler32 (adler32-zlib) [13911.093674] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [13911.441939] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [13913.199665] Key type lgssc registered [13914.896554] Lustre: Echo OBD driver; http://www.lustre.org/ [14005.654446] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14014.551871] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14042.382640] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14044.241567] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [14047.230096] Lustre: Unmounted lustre-client [14083.978218] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 12:59:56 (1788454796) [14125.726315] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14134.611168] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14177.875585] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14178.772995] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [14181.417965] Lustre: Unmounted lustre-client [14227.157807] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 13:02:20 (1788454940) [14238.176616] LNet: 1 local NIs in recovery (showing 1): 192.168.204.3@tcp [14892.524511] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14899.930462] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14937.878619] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15044.222554] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 13:15:57 (1788455757) [15056.987902] Key type lgssc unregistered [15057.185922] LNet: 290340:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15057.196684] LNetError: 290340:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15057.217560] LNet: Removed LNI 192.168.204.3@tcp [15057.954175] Key type .llcrypt unregistered [15057.957245] Key type ._llcrypt unregistered [15185.924284] Key type ._llcrypt registered [15185.928567] Key type .llcrypt registered [15186.449025] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15186.484075] alg: No test for adler32 (adler32-zlib) [15187.530686] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [15187.777360] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [15189.431241] Key type lgssc registered [15190.742627] Lustre: Echo OBD driver; http://www.lustre.org/ [15291.791489] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15299.422899] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15327.757282] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15360.397612] Key type lgssc unregistered [15360.664316] LNet: 294040:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15360.670742] LNetError: 294040:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15360.689028] LNet: Removed LNI 192.168.204.3@tcp [15361.326885] Key type .llcrypt unregistered [15361.328241] Key type ._llcrypt unregistered [15404.226887] Key type ._llcrypt registered [15404.232272] Key type .llcrypt registered [15406.291674] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 13:21:59 (1788456119) [15428.741518] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15428.941531] alg: No test for adler32 (adler32-zlib) [15430.177932] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [15430.542502] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [15432.367333] Key type lgssc registered [15434.116652] Lustre: Echo OBD driver; http://www.lustre.org/ [15496.778599] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 13:23:30 (1788456210) [15509.977059] Key type lgssc unregistered [15510.245745] LNet: 297602:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15510.257149] LNetError: 297602:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15510.288315] LNet: Removed LNI 192.168.204.3@tcp [15511.103820] Key type .llcrypt unregistered [15511.114421] Key type ._llcrypt unregistered [15523.484499] Key type ._llcrypt registered [15523.489132] Key type .llcrypt registered [15524.046727] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15524.059704] alg: No test for adler32 (adler32-zlib) [15525.275982] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [15525.550714] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [15527.300331] Key type lgssc registered [15528.954713] Lustre: Echo OBD driver; http://www.lustre.org/ [15595.685608] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15602.974585] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15629.175458] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15988.104976] LNet: Added LNI 192.168.204.3@tcp50 [8/256/0/180] [15992.264952] LNet: Removed LNI 192.168.204.3@tcp [15995.186920] Lustre: 302017:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff9601c5354800: config has no valid NIDs, force dynamic NIDs [15996.605498] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [15996.837386] Lustre: Unmounted lustre-client [16001.361785] Key type lgssc unregistered [16001.970370] LNet: 302279:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16001.984841] LNetError: 302279:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16002.005419] LNet: Removed LNI 192.168.204.3@tcp50 [16002.736086] Key type .llcrypt unregistered [16002.745241] Key type ._llcrypt unregistered [16023.233816] Key type ._llcrypt registered [16023.239198] Key type .llcrypt registered [16023.996867] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [16024.024907] alg: No test for adler32 (adler32-zlib) [16025.406808] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [16025.766743] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [16027.471543] Key type lgssc registered [16029.281589] Lustre: Echo OBD driver; http://www.lustre.org/ [16099.383677] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16108.324572] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16117.538211] LNet: Added LNI 192.168.204.3@tcp50 [8/256/0/180] [16118.401473] LNet: Removed LNI 192.168.204.3@tcp [16119.614402] Lustre: 304971:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff9601c343f000: config has no valid NIDs, force dynamic NIDs [16120.992949] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [16121.690365] Lustre: Unmounted lustre-client [16162.145170] Key type lgssc unregistered [16162.730643] LNet: 305843:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16162.748417] LNetError: 305843:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16162.785829] LNet: Removed LNI 192.168.204.3@tcp50 [16163.480316] Key type .llcrypt unregistered [16163.482053] Key type ._llcrypt unregistered [16176.667652] Key type ._llcrypt registered [16176.673157] Key type .llcrypt registered [16178.306344] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 13:34:51 (1788456891) [16200.578598] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16200.628670] alg: No test for adler32 (adler32-zlib) [16201.916817] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [16202.461254] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [16204.311267] Key type lgssc registered [16206.144596] Lustre: Echo OBD driver; http://www.lustre.org/ [16304.679563] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16311.493778] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16339.615462] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16378.007485] Key type lgssc unregistered [16378.283751] LNet: 310598:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16378.289913] LNetError: 310598:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16378.314713] LNet: Removed LNI 192.168.204.3@tcp [16379.022401] Key type .llcrypt unregistered [16379.026847] Key type ._llcrypt unregistered [16393.117818] Key type ._llcrypt registered [16393.122188] Key type .llcrypt registered [16395.176344] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 13:38:28 (1788457108) [16398.519248] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16398.553355] alg: No test for adler32 (adler32-zlib) [16399.653703] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [16399.965311] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [16401.703219] Key type lgssc registered [16402.985229] Lustre: Echo OBD driver; http://www.lustre.org/ [16460.373402] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16466.937973] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16492.306812] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16495.176800] Lustre: Mounted lustre-client - version 2.17.56_51_g2aa7cea [16561.402837] Lustre: Mounted fs15246-client - version 2.17.56_51_g2aa7cea [16578.918169] Lustre: Unmounted fs15246-client [16609.680616] Lustre: Unmounted lustre-client [16641.782248] Key type lgssc unregistered [16642.048086] LNet: 315463:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16642.057906] LNetError: 315463:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16642.086410] LNet: Removed LNI 192.168.204.3@tcp [16642.897389] Key type .llcrypt unregistered [16642.900706] Key type ._llcrypt unregistered [16657.151190] Key type ._llcrypt registered [16657.153421] Key type .llcrypt registered [16658.595476] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 13:42:52 (1788457372) [16660.736780] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16660.926596] alg: No test for adler32 (adler32-zlib) [16661.996356] Lustre: Lustre: Build Version: 2.17.56_51_g2aa7cea [16662.341759] LNet: Added LNI 192.168.204.3@tcp [8/256/0/180] [16664.183367] Key type lgssc registered [16665.893207] Lustre: Echo OBD driver; http://www.lustre.org/ [16820.291562] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16827.322361] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16853.922250] Lustre: DEBUG MARKER: oleg403-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16973.633600] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 16693 sec ========= 13:48:06 (1788457686) [16975.619306] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 13:48:09 (1788457689) === [16981.464590] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 13:48:14 (1788457694) === [17031.463698] Key type lgssc unregistered [17031.669952] LNet: 324739:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [17031.675893] LNetError: 324739:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [17031.686097] LNet: Removed LNI 192.168.204.3@tcp [17032.612809] Key type .llcrypt unregistered [17032.616588] Key type ._llcrypt unregistered