[ 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 429706621 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2400.000 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.001012] APIC: Switch to symmetric I/O mode setup [ 0.002382] x2apic enabled [ 0.003010] Switched APIC routing to physical x2apic. [ 0.004012] kvm-guest: setup PV IPIs [ 0.007000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.007000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x22983777dd9, max_idle_ns: 440795300422 ns [ 0.007020] Calibrating delay loop (skipped) preset value.. 4800.00 BogoMIPS (lpj=2400000) [ 0.008013] pid_max: default: 32768 minimum: 301 [ 0.009136] LSM: Security Framework initializing [ 0.010043] Yama: becoming mindful. [ 0.010860] SELinux: Initializing. [ 0.011068] *** VALIDATE selinux *** [ 0.020607] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.025724] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.026197] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.027114] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028109] *** VALIDATE tmpfs *** [ 0.030314] *** VALIDATE proc *** [ 0.031246] *** VALIDATE cgroup *** [ 0.032008] *** VALIDATE cgroup2 *** [ 0.034177] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.035147] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036009] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.038026] Spectre V2 : User space: Vulnerable [ 0.039015] Speculative Store Bypass: Vulnerable [ 0.042561] debug: unmapping init [mem 0xffffffff85659000-0xffffffff85660fff] [ 0.044200] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.045683] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.046023] ... version: 2 [ 0.047012] ... bit width: 48 [ 0.048010] ... generic registers: 4 [ 0.048902] ... value mask: 0000ffffffffffff [ 0.049016] ... max period: 00007fffffffffff [ 0.050019] ... fixed-purpose events: 3 [ 0.051015] ... event mask: 000000070000000f [ 0.052321] rcu: Hierarchical SRCU implementation. [ 0.054737] smp: Bringing up secondary CPUs ... [ 0.055602] x86: Booting SMP configuration: [ 0.056030] .... node #0, CPUs: #1 #2 #3 [ 0.059308] smp: Brought up 1 node, 4 CPUs [ 0.060909] smpboot: Max logical packages: 1 [ 0.061009] smpboot: Total of 4 processors activated (19200.00 BogoMIPS) [ 0.146022] node 0 deferred pages initialised in 84ms [ 0.149303] devtmpfs: initialized [ 0.151297] x86/mm: Memory block size: 128MB [ 0.155093] gcov: version magic: 0x41383552 [ 0.157412] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.161103] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.164285] pinctrl core: initialized pinctrl subsystem [ 0.166189] [ 0.166828] ************************************************************* [ 0.169014] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.171012] ** ** [ 0.174014] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.176013] ** ** [ 0.179012] ** This means that this kernel is built to expose internal ** [ 0.181012] ** IOMMU data structures, which may compromise security on ** [ 0.183016] ** your system. ** [ 0.186013] ** ** [ 0.188012] ** If you see this message and you are not debugging the ** [ 0.191013] ** kernel, report this immediately to your vendor! ** [ 0.193010] ** ** [ 0.196012] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.198011] ************************************************************* [ 0.201819] NET: Registered protocol family 16 [ 0.203503] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.206113] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.208104] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.211111] cpuidle: using governor menu [ 0.213000] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.214462] PCI: Using configuration type 1 for base access [ 0.216127] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.226046] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.227021] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.229057] cryptd: max_cpu_qlen set to 1000 [ 0.232276] ACPI: Added _OSI(Module Device) [ 0.234015] ACPI: Added _OSI(Processor Device) [ 0.236015] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.237013] ACPI: Added _OSI(Processor Aggregator Device) [ 0.242148] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.247417] ACPI: Interpreter enabled [ 0.249051] ACPI: PM: (supports S0 S3 S4 S5) [ 0.250018] ACPI: Using IOAPIC for interrupt routing [ 0.252150] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.256447] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.267000] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.269085] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.272030] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.276085] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.280400] acpiphp: Slot [2] registered [ 0.281152] acpiphp: Slot [5] registered [ 0.283123] acpiphp: Slot [6] registered [ 0.285140] acpiphp: Slot [3] registered [ 0.286103] acpiphp: Slot [4] registered [ 0.288105] acpiphp: Slot [7] registered [ 0.289172] acpiphp: Slot [8] registered [ 0.291101] acpiphp: Slot [9] registered [ 0.293112] acpiphp: Slot [10] registered [ 0.295137] acpiphp: Slot [11] registered [ 0.296107] acpiphp: Slot [12] registered [ 0.298159] acpiphp: Slot [13] registered [ 0.300108] acpiphp: Slot [14] registered [ 0.301120] acpiphp: Slot [15] registered [ 0.303112] acpiphp: Slot [16] registered [ 0.305104] acpiphp: Slot [17] registered [ 0.306132] acpiphp: Slot [18] registered [ 0.308133] acpiphp: Slot [19] registered [ 0.310102] acpiphp: Slot [20] registered [ 0.311089] acpiphp: Slot [21] registered [ 0.313119] acpiphp: Slot [22] registered [ 0.315081] acpiphp: Slot [23] registered [ 0.316131] acpiphp: Slot [24] registered [ 0.318098] acpiphp: Slot [25] registered [ 0.320135] acpiphp: Slot [26] registered [ 0.321129] acpiphp: Slot [27] registered [ 0.322110] acpiphp: Slot [28] registered [ 0.324105] acpiphp: Slot [29] registered [ 0.325155] acpiphp: Slot [30] registered [ 0.327113] acpiphp: Slot [31] registered [ 0.329081] PCI host bridge to bus 0000:00 [ 0.330018] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.332033] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.335028] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.337028] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.340024] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.343023] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.344182] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.347566] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.351478] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.360013] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.364064] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.368019] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.370017] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.372020] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.374640] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.378084] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.380036] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.382577] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.385012] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.392013] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.397014] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.401950] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.413030] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.424024] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.450022] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.478101] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.500019] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.507018] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.525016] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.534806] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.537411] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.540360] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.542341] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.545255] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.550071] iommu: Default domain type: Passthrough [ 0.552497] SCSI subsystem initialized [ 0.554135] ACPI: bus type USB registered [ 0.555120] usbcore: registered new interface driver usbfs [ 0.557073] usbcore: registered new interface driver hub [ 0.559093] usbcore: registered new device driver usb [ 0.561207] pps_core: LinuxPPS API ver. 1 registered [ 0.563013] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.566071] PTP clock support registered [ 0.569076] EDAC MC: Ver: 3.0.0 [ 0.570388] PCI: Using ACPI for IRQ routing [ 0.571348] NetLabel: Initializing [ 0.572000] NetLabel: domain hash size = 128 [ 0.574013] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.576084] NetLabel: unlabeled traffic allowed by default [ 0.579147] vgaarb: loaded [ 0.581007] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.582020] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.589000] clocksource: Switched to clocksource kvm-clock [ 0.694847] VFS: Disk quotas dquot_6.6.0 [ 0.696550] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.699239] *** VALIDATE ramfs *** [ 0.700714] *** VALIDATE hugetlbfs *** [ 0.702237] pnp: PnP ACPI init [ 0.705522] pnp: PnP ACPI: found 6 devices [ 0.730123] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.732175] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.733822] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.735431] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.737120] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.738861] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.741495] NET: Registered protocol family 2 [ 0.743917] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.748566] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.753134] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.758936] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.763682] TCP: Hash tables configured (established 65536 bind 65536) [ 0.766637] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.769961] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.773127] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.776357] NET: Registered protocol family 1 [ 0.779125] RPC: Registered named UNIX socket transport module. [ 0.781633] RPC: Registered udp transport module. [ 0.783747] RPC: Registered tcp transport module. [ 0.785560] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.788491] NET: Registered protocol family 44 [ 0.790031] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.792071] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.793966] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.795921] PCI: CLS 0 bytes, default 64 [ 0.797468] Unpacking initramfs... [ 2.319948] debug: unmapping init [mem 0xffff8ec7fcc64000-0xffff8ec7fffcffff] [ 2.323310] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.324766] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.327186] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x22983777dd9, max_idle_ns: 440795300422 ns [ 2.846859] Initialise system trusted keyrings [ 2.848109] Key type blacklist registered [ 2.849586] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.855752] zbud: loaded [ 2.858792] *** VALIDATE nfs *** [ 2.860140] *** VALIDATE nfs4 *** [ 2.862031] pstore: using deflate compression [ 2.865453] Platform Keyring initialized [ 2.972100] NET: Registered protocol family 38 [ 2.974865] Key type asymmetric registered [ 2.976652] Asymmetric key parser 'x509' registered [ 2.978703] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.981990] io scheduler mq-deadline registered [ 2.983218] io scheduler kyber registered [ 2.984651] io scheduler bfq registered [ 2.986335] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.988953] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.990943] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.992794] ACPI: Power Button [PWRF] [ 2.997815] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.004558] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.016044] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.045367] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.073068] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.079169] Non-volatile memory driver v1.3 [ 3.080833] Linux agpgart interface v0.103 [ 3.116151] virtio_blk virtio1: [vda] 147160 512-byte logical blocks (75.3 MB/71.9 MiB) [ 3.119380] vda: detected capacity change from 0 to 75345920 [ 3.134066] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.137184] vdb: detected capacity change from 0 to 1073741824 [ 3.145685] libphy: Fixed MDIO Bus: probed [ 3.151858] usbcore: registered new interface driver usbserial_generic [ 3.153987] usbserial: USB Serial support registered for generic [ 3.156246] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.160561] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.162522] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.165234] mousedev: PS/2 mouse device common for all mice [ 3.168207] rtc_cmos 00:05: RTC can wake from S4 [ 3.171143] rtc_cmos 00:05: registered as rtc0 [ 3.174604] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.174911] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.181027] intel_pstate: CPU model not supported [ 3.184272] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.186780] hid: raw HID events driver (C) Jiri Kosina [ 3.190441] usbcore: registered new interface driver usbhid [ 3.192663] usbhid: USB HID core driver [ 3.194328] drop_monitor: Initializing network drop monitor service [ 3.196980] Initializing XFRM netlink socket [ 3.199869] NET: Registered protocol family 10 [ 3.200393] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.204429] Segment Routing with IPv6 [ 3.207506] NET: Registered protocol family 17 [ 3.209960] mpls_gso: MPLS GSO support [ 3.216137] RAS: Correctable Errors collector initialized. [ 3.218639] AVX version of gcm_enc/dec engaged. [ 3.220303] AES CTR mode by8 optimization enabled [ 3.297827] sched_clock: Marking stable (3297794049, 0)->(4177762574, -879968525) [ 3.300164] registered taskstats version 1 [ 3.302110] Loading compiled-in X.509 certificates [ 3.304620] zswap: loaded using pool lzo/zbud [ 3.330813] Key type big_key registered [ 3.342814] Key type encrypted registered [ 3.344372] ima: No TPM chip found, activating TPM-bypass! [ 3.346237] ima: Allocated hash algorithm: sha1 [ 3.347831] ima: No architecture policies found [ 3.348977] evm: Initialising EVM extended attributes: [ 3.350161] evm: security.selinux [ 3.351222] evm: security.ima [ 3.352395] evm: security.capability [ 3.353713] evm: HMAC attrs: 0x1 [ 3.356038] rtc_cmos 00:05: setting system clock to 2026-09-11 12:41:23 UTC (1789130483) [ 3.362098] debug: unmapping init [mem 0xffffffff86603000-0xffffffff867fffff] [ 3.365485] debug: unmapping init [mem 0xffffffff85382000-0xffffffff85658fff] [ 3.376100] Write protecting the kernel read-only data: 28672k [ 3.379797] debug: unmapping init [mem 0xffffffff83a03000-0xffffffff83bfffff] [ 3.382699] debug: unmapping init [mem 0xffffffff84314000-0xffffffff843fffff] [ 3.415327] 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.424036] systemd[1]: Detected virtualization kvm. [ 3.425547] systemd[1]: Detected architecture x86-64. [ 3.427272] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.450918] systemd[1]: No hostname configured. [ 3.452667] systemd[1]: Set hostname to . [ 3.454668] random: systemd: uninitialized urandom read (16 bytes read) [ 3.456404] systemd[1]: Initializing machine ID from random generator. [ 3.498727] random: ln: uninitialized urandom read (6 bytes read) [ 3.580812] random: systemd: uninitialized urandom read (16 bytes read) [ 3.583546] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 3.589454] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ 3.593884] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ OK ] Listening on udev Control Socket. [ OK ] Started Memstrack Anylazing Service. [ OK ] Reached target Slices. [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... Starting Apply Kernel Variables... Starting Create list of required st…ce nodes for the current kernel... Starting Setup Virtual Console... [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Reached target Swap. [ OK ] Reached target Initrd Root Device. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Setup Virtual Console. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Journal Service. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.155734] device-mapper: uevent: version 1.0.3 [ 4.158161] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 4.757911] random: fast init done [ 4.767830] virtio_net virtio0 ens2: renamed from eth0 [ 4.785849] scsi host0: ata_piix [ 4.797933] scsi host1: ata_piix [ 4.799559] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.802351] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.990852] dracut-initqueue[587]: RTNETLINK answers: File exists [ 9.864063] random: crng init done [ 9.865153] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 10.012533] 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 Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Swap. [ OK ] Stopped Apply Kernel Variables. Stopping udev Kernel Device Manager... [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.071859] printk: systemd: 23 output lines suppressed due to ratelimiting [ 11.329157] SELinux: Disabled at runtime. [ 11.389081] 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.394223] systemd[1]: Detected virtualization kvm. [ 11.395412] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 11.897664] systemd[1]: initrd-switch-root.service: Succeeded. [ 11.901311] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 11.910303] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 11.913456] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 11.915829] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 11.921527] systemd[1]: Starting Journal Service... Starting Journal Service... [ 11.926839] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ OK ] Started Forward Password Requests to Wall Directory Watch. [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 Kernel Socket. [ OK ] Stopped target Switch Root. [ OK ] Reached target rpc_pipefs.target. Mounting POSIX Message Queue File System... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. Mounting Kernel Debug File System... Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on initctl Compatibility Named Pipe. [ 12.011624] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Listening on Process Core Dump Socket. [ OK ] Stopped target Initrd File Systems. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. Starting Remount Root and Kernel File Systems... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... Starting Apply Kernel Variables... [ OK ] Stopped target Initrd Root File System. [ OK ] Created slice system-getty.slice. Starting Create list of required st…ce nodes for the current kernel... Mounting Huge Pages File System... [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Started Journal Service. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Mounted Kernel Debug File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Huge Pages File System. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 12.461545] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 12.825975] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 12.893575] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 12.994132] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.006803] EDAC sbridge: Ver: 1.1.2 [ 14.316329] Key type dns_resolver registered [ 14.647685] NFS: Registering the id_resolver key type [ 14.649864] Key type id_resolver registered [ 14.651357] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... Starting Rebuild Dynamic Linker Cache... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Login Service... [ OK ] Started irqbalance daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started Daily Cleanup of Temporary Directories. Starting Restore /run/initramfs on shutdown... [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Reached target sshd-keygen.target. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... [ OK ] Started OpenSSH server daemon. Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting System Logging Service... Starting Crash recovery kernel arming... [ 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 oleg120-client login: [ 52.673315] libcfs: loading out-of-tree module taints kernel. [ 52.908193] Key type ._llcrypt registered [ 52.911419] Key type .llcrypt registered [ 53.426617] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 53.440819] alg: No test for adler32 (adler32-zlib) [ 54.953703] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [ 55.858644] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 57.603226] Key type lgssc registered [ 59.751103] hrtimer: interrupt took 3094740 ns [ 60.608344] Lustre: Echo OBD driver; http://www.lustre.org/ [ 246.049483] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 251.691857] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 268.567848] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing check_logdir /tmp/testlogs/ [ 271.843887] Lustre: lustre-OST0000-osc-ffff8ec849bbb000: disconnect after 23s idle [ 273.714093] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing yml_node [ 277.595708] Lustre: DEBUG MARKER: Client: 2.17.57.132 [ 280.607796] Lustre: DEBUG MARKER: MDS: 2.17.57.132 [ 284.006942] Lustre: DEBUG MARKER: OSS: 2.17.57.132 [ 286.122905] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Fri Sep 11 08:46:04 EDT 2026 [ 306.094680] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 307.639791] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 309.332783] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 319.661402] Lustre: Unmounted lustre-client [ 469.881713] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 475.806207] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 503.414488] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 544.348301] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 08:50:22 (1789131022) [ 547.242307] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 549.473892] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 08:50:27 (1789131027) [ 587.878393] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 595.161385] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 619.033217] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 619.973300] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 625.203093] Lustre: Unmounted lustre-client [ 654.270670] Key type lgssc unregistered [ 654.537292] LNet: 15056:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 654.542990] LNetError: 15056:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 654.556047] LNet: Removed LNI 192.168.201.20@tcp [ 655.675203] Key type .llcrypt unregistered [ 655.680561] Key type ._llcrypt unregistered [ 676.142839] Key type ._llcrypt registered [ 676.151752] Key type .llcrypt registered [ 677.552923] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 08:52:35 (1789131155) [ 679.891812] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 679.967541] alg: No test for adler32 (adler32-zlib) [ 681.279463] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [ 681.589978] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 683.376520] Key type lgssc registered [ 685.091521] Lustre: Echo OBD driver; http://www.lustre.org/ [ 770.261766] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 855.752876] Lustre: Unmounted lustre-client [ 883.054964] Key type lgssc unregistered [ 883.399748] LNet: 18853:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 883.404039] LNetError: 18853:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 883.428213] LNet: Removed LNI 192.168.201.20@tcp [ 884.486411] Key type .llcrypt unregistered [ 884.492940] Key type ._llcrypt unregistered [ 896.121505] Key type ._llcrypt registered [ 896.124175] Key type .llcrypt registered [ 897.614478] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 08:56:16 (1789131376) [ 901.350300] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 901.389336] alg: No test for adler32 (adler32-zlib) [ 902.616821] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [ 903.112861] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 904.844076] Key type lgssc registered [ 906.607480] Lustre: Echo OBD driver; http://www.lustre.org/ [ 964.814524] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 970.685653] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 996.714422] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 999.535754] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 1011.174732] Lustre: Unmounted lustre-client [ 1039.733422] Key type lgssc unregistered [ 1040.062745] LNet: 22462:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1040.067606] LNetError: 22462:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1040.099089] LNet: Removed LNI 192.168.201.20@tcp [ 1040.902610] Key type .llcrypt unregistered [ 1040.906128] Key type ._llcrypt unregistered [ 1051.449946] Key type ._llcrypt registered [ 1051.452095] Key type .llcrypt registered [ 1053.101653] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 08:58:51 (1789131531) [ 1055.664818] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1055.735821] alg: No test for adler32 (adler32-zlib) [ 1056.891520] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [ 1057.245940] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 1058.881616] Key type lgssc registered [ 1060.366344] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1118.610405] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1124.291695] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1146.278551] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1147.928852] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 1157.867487] Lustre: Unmounted lustre-client [ 1194.931177] Key type lgssc unregistered [ 1195.263577] LNet: 25675:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1195.270348] LNetError: 25675:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1196.336395] LNet: Removed LNI 192.168.201.20@tcp [ 1196.979631] Key type .llcrypt unregistered [ 1196.981416] Key type ._llcrypt unregistered [ 1213.268297] Key type ._llcrypt registered [ 1213.272392] Key type .llcrypt registered [ 1214.831754] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 09:01:33 (1789131693) [ 1216.995644] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1217.102578] alg: No test for adler32 (adler32-zlib) [ 1218.386436] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [ 1218.686310] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 1220.416217] Key type lgssc registered [ 1221.674478] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1274.590699] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1282.605648] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1307.496686] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1309.278788] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 1314.285110] Lustre: lustre-MDT0000-mdc-ffff8ec8582d6000: Connection to lustre-MDT0000 (at 192.168.201.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1319.416600] Lustre: lustre-MDT0001-mdc-ffff8ec8582d6000: Connection to lustre-MDT0001 (at 192.168.201.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1326.865404] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1335.776765] Lustre: 26633:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789131799/real 1789131799] req@000000002f4e37e1 x1876040559367552/t0(0) o400->MGC192.168.201.120@tcp@192.168.201.120@tcp:26/25 lens 224/224 e 0 to 1 dl 1789131815 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1335.829663] LustreError: MGC192.168.201.120@tcp: Connection to MGS (at 192.168.201.120@tcp) was lost; in progress operations using this service will fail [ 1337.297612] Lustre: Unmounted lustre-client [ 1360.709116] Key type lgssc unregistered [ 1360.922109] LNet: 29332:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1360.932638] LNetError: 29332:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1360.946521] LNet: Removed LNI 192.168.201.20@tcp [ 1361.750296] Key type .llcrypt unregistered [ 1361.752378] Key type ._llcrypt unregistered [ 1376.928761] Key type ._llcrypt registered [ 1376.930696] Key type .llcrypt registered [ 1378.241749] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 09:04:16 (1789131856) [ 1380.619412] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1380.635314] alg: No test for adler32 (adler32-zlib) [ 1381.763723] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [ 1381.934503] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 1383.568199] Key type lgssc registered [ 1384.847843] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1443.172316] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1449.711701] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1471.922481] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1473.687742] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 1483.773089] Lustre: lustre-OST0000-osc-ffff8ec849bbe800: Connection to lustre-OST0000 (at 192.168.201.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1488.946677] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1499.358078] Lustre: Unmounted lustre-client [ 1527.829971] Key type lgssc unregistered [ 1528.163909] LNet: 32943:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1528.176935] LNetError: 32943:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1528.194865] LNet: Removed LNI 192.168.201.20@tcp [ 1528.776868] Key type .llcrypt unregistered [ 1528.783325] Key type ._llcrypt unregistered [ 1546.520177] Key type ._llcrypt registered [ 1546.522678] Key type .llcrypt registered [ 1547.262306] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 09:07:06 (1789132026) [ 1550.181972] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1550.209517] alg: No test for adler32 (adler32-zlib) [ 1551.475458] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [ 1551.866093] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 1553.545171] Key type lgssc registered [ 1554.636238] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1607.626839] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1613.075821] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1636.730087] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1638.236047] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 1645.408411] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1649.463752] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1660.442064] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-09-11 9h08m59s [ 1662.277753] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1669.101160] Lustre: lustre-MDT0000-mdc-ffff8ec846e0f800: Connection to lustre-MDT0000 (at 192.168.201.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1672.868853] LustreError: 36737:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff8ec846e0f800: can't stat MDS #0: rc = -19 [ 1674.356371] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1682.413770] LustreError: MGC192.168.201.120@tcp: Connection to MGS (at 192.168.201.120@tcp) was lost; in progress operations using this service will fail [ 1682.446482] Lustre: Evicted from MGS (at 192.168.201.120@tcp) after server handle changed from 0xbcf4f470f7b5b6a6 to 0xbcf4f470f7b5b874 [ 1682.462845] Lustre: MGC192.168.201.120@tcp: Connection restored to 192.168.201.120@tcp (at 192.168.201.120@tcp) [ 1687.529194] LustreError: lustre-MDT0000-mdc-ffff8ec846e0f800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1687.555529] Lustre: lustre-MDT0000-mdc-ffff8ec846e0f800: Connection restored to 192.168.201.120@tcp (at 192.168.201.120@tcp) [ 1692.167557] Lustre: DEBUG MARKER: Wait for df (36737) ... [ 1693.972122] Lustre: DEBUG MARKER: done [ 1697.232483] Lustre: Unmounted lustre-client [ 1736.912332] Key type lgssc unregistered [ 1737.222752] LNet: 37756:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1737.232516] LNetError: 37756:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1738.296760] LNet: Removed LNI 192.168.201.20@tcp [ 1739.213119] Key type .llcrypt unregistered [ 1739.217841] Key type ._llcrypt unregistered [ 1779.134776] Key type ._llcrypt registered [ 1779.138449] Key type .llcrypt registered [ 1780.602121] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 09:10:59 (1789132259) [ 1782.779321] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1782.808524] alg: No test for adler32 (adler32-zlib) [ 1783.874420] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [ 1784.107029] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 1785.784231] Key type lgssc registered [ 1787.155496] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1848.925027] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1856.150810] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1883.284757] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1886.296429] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 1891.541736] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-09-11 9h12m49s [ 1893.059928] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1900.466984] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1903.374835] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1924.064133] Lustre: 41909:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789132383/real 1789132383] req@00000000bc91b8f5 x1876041152862976/t0(0) o101->lustre-MDT0000-mdc-ffff8ec84806e800@192.168.201.120@tcp:12/10 lens 576/1152 e 0 to 1 dl 1789132403 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1924.085683] Lustre: lustre-MDT0000-mdc-ffff8ec84806e800: Connection to lustre-MDT0000 (at 192.168.201.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1924.145971] Lustre: lustre-MDT0000-mdc-ffff8ec84806e800: Connection restored to 192.168.201.120@tcp (at 192.168.201.120@tcp) [ 1945.324269] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1948.812852] Lustre: DEBUG MARKER: done [ 1953.397875] Lustre: Unmounted lustre-client [ 1991.747361] Key type lgssc unregistered [ 1992.176623] LNet: 42790:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1992.180605] LNetError: 42790:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1992.204487] LNet: Removed LNI 192.168.201.20@tcp [ 1992.913735] Key type .llcrypt unregistered [ 1992.916059] Key type ._llcrypt unregistered [ 2029.400880] Key type ._llcrypt registered [ 2029.420247] Key type .llcrypt registered [ 2031.450237] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 09:15:09 (1789132509) [ 2033.367553] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 2036.131671] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 09:15:13 (1789132513) [ 2040.876605] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2041.185819] alg: No test for adler32 (adler32-zlib) [ 2042.560544] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [ 2042.914851] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 2044.656699] Key type lgssc registered [ 2046.165124] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2080.219995] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 09:15:58 (1789132558) [ 2117.770768] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2124.249330] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2150.087264] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2153.157065] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 2157.345766] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 2160.539084] Lustre: Unmounted lustre-client [ 2176.956101] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 2233.321672] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2240.191723] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2241.487415] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 2247.978627] Lustre: Unmounted lustre-client [ 2319.399251] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2326.097731] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2326.731938] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 2332.178950] Lustre: Unmounted lustre-client [ 2358.797412] Lustre: DEBUG MARKER: files compared the same [ 2375.724343] Key type lgssc unregistered [ 2376.189518] LNet: 50425:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2376.197732] LNetError: 50425:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2376.219249] LNet: Removed LNI 192.168.201.20@tcp [ 2377.057347] Key type .llcrypt unregistered [ 2377.059968] Key type ._llcrypt unregistered [ 2396.717475] Key type ._llcrypt registered [ 2396.726330] Key type .llcrypt registered [ 2398.596784] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 09:21:16 (1789132876) [ 2401.745813] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2401.804712] alg: No test for adler32 (adler32-zlib) [ 2402.942784] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [ 2403.277635] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 2405.042073] Key type lgssc registered [ 2406.620792] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2466.068226] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2473.573693] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2497.255595] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2499.950497] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 2505.102019] Lustre: Unmounted lustre-client [ 2536.341117] Key type lgssc unregistered [ 2536.730727] LNet: 54017:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2536.738083] LNetError: 54017:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2537.771423] LNet: Removed LNI 192.168.201.20@tcp [ 2538.778987] Key type .llcrypt unregistered [ 2538.785653] Key type ._llcrypt unregistered [ 2559.120935] Key type ._llcrypt registered [ 2559.125142] Key type .llcrypt registered [ 2560.768774] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 09:23:59 (1789133039) [ 2563.673977] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2563.856564] alg: No test for adler32 (adler32-zlib) [ 2565.058911] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [ 2565.497765] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 2567.184246] Key type lgssc registered [ 2568.488272] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2662.039224] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2701.176628] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2707.575376] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2741.735073] Key type lgssc unregistered [ 2742.077186] LNet: 57516:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2742.087590] LNetError: 57516:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2742.145282] LNet: Removed LNI 192.168.201.20@tcp [ 2743.427731] Key type .llcrypt unregistered [ 2743.432541] Key type ._llcrypt unregistered [ 2761.453692] Key type ._llcrypt registered [ 2761.455303] Key type .llcrypt registered [ 2762.689505] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 09:27:21 (1789133241) [ 2765.960164] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2766.012894] alg: No test for adler32 (adler32-zlib) [ 2767.005821] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [ 2767.193409] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 2768.872196] Key type lgssc registered [ 2769.877479] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2843.597030] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 2851.051239] Lustre: Unmounted lustre-client [ 2885.960603] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 09:29:24 (1789133364) [ 3009.623224] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3032.656260] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 3039.564973] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3039.570845] LustreError: 63993:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff8ec845344800: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 3039.588900] Lustre: 58500:0:(llite_lib.c:4366:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.201.120@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 3049.925353] Lustre: Unmounted lustre-client [ 3092.278317] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 09:32:51 (1789133571) [ 3105.164568] Key type lgssc unregistered [ 3105.438596] LNet: 65219:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3105.453049] LNetError: 65219:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3105.467875] LNet: Removed LNI 192.168.201.20@tcp [ 3106.260875] Key type .llcrypt unregistered [ 3106.264554] Key type ._llcrypt unregistered [ 3123.423223] Key type ._llcrypt registered [ 3123.438064] Key type .llcrypt registered [ 3124.112836] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3124.128828] alg: No test for adler32 (adler32-zlib) [ 3125.346454] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [ 3125.797052] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 3127.616230] Key type lgssc registered [ 3128.801151] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3195.817282] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3201.698699] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3221.756962] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3223.344278] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 3230.749500] Lustre: Unmounted lustre-client [ 3258.755486] Key type lgssc unregistered [ 3258.973484] LNet: 68245:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3258.990863] LNetError: 68245:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3260.011751] LNet: Removed LNI 192.168.201.20@tcp [ 3260.810198] Key type .llcrypt unregistered [ 3260.813280] Key type ._llcrypt unregistered [ 3275.108844] Key type ._llcrypt registered [ 3275.115031] Key type .llcrypt registered [ 3275.933500] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3275.955080] alg: No test for adler32 (adler32-zlib) [ 3277.099417] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [ 3277.493623] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 3279.256254] Key type lgssc registered [ 3280.328558] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3394.762119] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3400.615401] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3425.303724] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3428.062058] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 3431.724125] Lustre: Unmounted lustre-client [ 3470.202796] Key type lgssc unregistered [ 3470.515701] LNet: 71682:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3470.522828] LNetError: 71682:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3470.533745] LNet: Removed LNI 192.168.201.20@tcp [ 3471.336496] Key type .llcrypt unregistered [ 3471.337925] Key type ._llcrypt unregistered [ 3489.413752] Key type ._llcrypt registered [ 3489.417480] Key type .llcrypt registered [ 3491.109939] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 09:39:29 (1789133969) [ 3493.919921] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3494.032131] alg: No test for adler32 (adler32-zlib) [ 3495.347631] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [ 3495.570555] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 3497.272713] Key type lgssc registered [ 3498.933466] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3556.104973] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3564.655629] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3590.097034] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3597.362049] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 3604.470039] Lustre: Unmounted lustre-client [ 3604.907726] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 3608.558052] Lustre: Unmounted lustre-client [ 3636.039227] Key type lgssc unregistered [ 3636.366220] LNet: 75374:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3636.370975] LNetError: 75374:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3636.389415] LNet: Removed LNI 192.168.201.20@tcp [ 3637.166392] Key type .llcrypt unregistered [ 3637.168576] Key type ._llcrypt unregistered [ 3650.837398] Key type ._llcrypt registered [ 3650.844097] Key type .llcrypt registered [ 3651.303095] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3651.317348] alg: No test for adler32 (adler32-zlib) [ 3652.462562] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [ 3652.785166] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 3654.568146] Key type lgssc registered [ 3655.991509] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3716.963710] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3723.986596] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3746.537522] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3757.646916] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 3762.120281] Lustre: Unmounted lustre-client [ 3791.054152] Key type lgssc unregistered [ 3791.304035] LNet: 78420:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3791.312327] LNetError: 78420:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3791.331870] LNet: Removed LNI 192.168.201.20@tcp [ 3792.131086] Key type .llcrypt unregistered [ 3792.134063] Key type ._llcrypt unregistered [ 3807.309751] Key type ._llcrypt registered [ 3807.312120] Key type .llcrypt registered [ 3808.568632] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 09:44:47 (1789134287) [ 3811.317578] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3811.332417] alg: No test for adler32 (adler32-zlib) [ 3812.472285] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [ 3812.722787] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 3814.392166] Key type lgssc registered [ 3815.515965] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3873.523036] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3881.053567] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3904.968589] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3916.819424] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 3928.103782] Lustre: lustre: root_squash is set to 0:0 [ 3928.134654] Lustre: lustre: nosquash_nids is cleared [ 3937.297655] Lustre: lustre: root_squash is set to 500:500 [ 3943.845438] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.201.20@tcp 192.168.0.[2,10]@tcp [ 3965.469522] Lustre: lustre: nosquash_nids set to 192.168.201.20/24@tcp [ 3968.147289] Lustre: Unmounted lustre-client [ 3998.866487] Key type lgssc unregistered [ 3999.283377] LNet: 82415:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3999.296447] LNetError: 82415:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3999.331977] LNet: Removed LNI 192.168.201.20@tcp [ 4000.291251] Key type .llcrypt unregistered [ 4000.293254] Key type ._llcrypt unregistered [ 4018.758736] Key type ._llcrypt registered [ 4018.761138] Key type .llcrypt registered [ 4019.997171] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 09:48:18 (1789134498) [ 4022.769390] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 4024.198475] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 09:48:23 (1789134503) [ 4026.371136] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4026.430767] alg: No test for adler32 (adler32-zlib) [ 4027.687923] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [ 4028.087414] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 4029.792134] Key type lgssc registered [ 4031.937140] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4094.334974] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4101.687359] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4123.867334] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4125.451697] Lustre: lustre: root_squash is set to 500:500 [ 4125.462142] Lustre: lustre: nosquash_nids set to 192.168.201.20/24@tcp [ 4136.010553] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 4141.498465] Lustre: Unmounted lustre-client [ 4170.074979] Key type lgssc unregistered [ 4170.381778] LNet: 86379:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4170.387250] LNetError: 86379:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4170.407838] LNet: Removed LNI 192.168.201.20@tcp [ 4171.391722] Key type .llcrypt unregistered [ 4171.398094] Key type ._llcrypt unregistered [ 4190.491962] Key type ._llcrypt registered [ 4190.496775] Key type .llcrypt registered [ 4191.991673] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 4193.941075] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 09:51:12 (1789134672) [ 4215.669379] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4215.706612] alg: No test for adler32 (adler32-zlib) [ 4216.806287] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [ 4217.077448] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 4218.792203] Key type lgssc registered [ 4220.357514] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4311.092905] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4316.645137] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4341.418233] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4413.926088] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4420.221135] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4442.660841] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4456.540816] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 4499.515922] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8ec8580e5800.ost_server_uuid 50 [ 4500.996552] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8ec8580e5800.ost_server_uuid in IDLE state after 0 sec [ 4501.838736] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 4506.465393] Lustre: Unmounted lustre-client [ 4508.245560] Lustre: Unmounted lustre-client [ 4552.417970] Key type lgssc unregistered [ 4552.770599] LNet: 94067:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4552.777038] LNetError: 94067:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4552.813320] LNet: Removed LNI 192.168.201.20@tcp [ 4553.398545] Key type .llcrypt unregistered [ 4553.404545] Key type ._llcrypt unregistered [ 4588.571900] Key type ._llcrypt registered [ 4588.574145] Key type .llcrypt registered [ 4589.705161] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 09:57:48 (1789135068) [ 4611.093931] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4611.122360] alg: No test for adler32 (adler32-zlib) [ 4612.154433] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [ 4612.365493] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 4614.112175] Key type lgssc registered [ 4615.146471] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4700.263569] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4706.800743] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4732.671265] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4734.639821] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 4756.384194] Lustre: 95430:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789135220/real 1789135220] req@00000000238fdffb x1876044118234240/t0(0) o400->lustre-OST0000-osc-ffff8ec86039e000@192.168.201.120@tcp:28/4 lens 224/224 e 0 to 1 dl 1789135236 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4756.411166] Lustre: lustre-OST0000-osc-ffff8ec86039e000: Connection to lustre-OST0000 (at 192.168.201.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4761.249108] Lustre: 95430:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789135225/real 1789135225] req@000000001eeb4f2f x1876044118234752/t0(0) o400->lustre-OST0000-osc-ffff8ec86039e000@192.168.201.120@tcp:28/4 lens 224/224 e 0 to 1 dl 1789135241 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4766.689083] Lustre: 95431:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789135230/real 1789135230] req@0000000042fa7819 x1876044118235264/t0(0) o400->lustre-OST0000-osc-ffff8ec86039e000@192.168.201.120@tcp:28/4 lens 224/224 e 0 to 1 dl 1789135246 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4767.361770] Lustre: lustre-OST0000-osc-ffff8ec86039e000: Connection restored to 192.168.201.120@tcp (at 192.168.201.120@tcp) [ 4771.808388] Lustre: 95431:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789135235/real 1789135235] req@000000003f73c2b1 x1876044118235776/t0(0) o400->lustre-OST0000-osc-ffff8ec86039e000@192.168.201.120@tcp:28/4 lens 224/224 e 0 to 1 dl 1789135251 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4771.827274] Lustre: lustre-MDT0000-mdc-ffff8ec86039e000: Connection to lustre-MDT0000 (at 192.168.201.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4787.171329] Lustre: 95430:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789135251/real 1789135251] req@00000000793d6fcb x1876044118238080/t0(0) o400->MGC192.168.201.120@tcp@192.168.201.120@tcp:26/25 lens 224/224 e 0 to 1 dl 1789135267 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4787.233234] LustreError: MGC192.168.201.120@tcp: Connection to MGS (at 192.168.201.120@tcp) was lost; in progress operations using this service will fail [ 4797.426834] Lustre: Evicted from MGS (at 192.168.201.120@tcp) after server handle changed from 0xbbdc8d85f1f24d5d to 0xbbdc8d85f1f24ef3 [ 4797.447144] Lustre: MGC192.168.201.120@tcp: Connection restored to 192.168.201.120@tcp (at 192.168.201.120@tcp) [ 4803.861931] Lustre: lustre-MDT0000-mdc-ffff8ec86039e000: Connection restored to 192.168.201.120@tcp (at 192.168.201.120@tcp) [ 4811.810443] Lustre: Unmounted lustre-client [ 4841.117940] Key type lgssc unregistered [ 4841.444953] LNet: 99747:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4841.452741] LNetError: 99747:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4841.484756] LNet: Removed LNI 192.168.201.20@tcp [ 4842.178394] Key type .llcrypt unregistered [ 4842.181228] Key type ._llcrypt unregistered [ 4858.698951] Key type ._llcrypt registered [ 4858.701525] Key type .llcrypt registered [ 4860.074939] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 10:02:18 (1789135338) [ 4874.415668] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4874.553153] alg: No test for adler32 (adler32-zlib) [ 4875.776513] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [ 4876.026417] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 4877.752202] Key type lgssc registered [ 4879.077507] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4916.945855] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5018.210585] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5024.489726] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5047.049214] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5049.704654] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 5052.416964] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 5055.452476] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 10:05:33 (1789135533) [ 5288.430645] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5298.778242] Lustre: Unmounted lustre-client [ 5442.918517] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5449.772659] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5476.263752] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5524.062871] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 10:13:22 (1789136002) [ 5634.052690] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5642.042762] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5671.928579] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5673.033165] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 5679.195775] Lustre: Unmounted lustre-client [ 5716.482653] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 10:16:34 (1789136194) [ 5852.338585] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5860.803133] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5891.501913] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5892.446618] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 5897.802317] Lustre: Unmounted lustre-client [ 5935.929695] Key type lgssc unregistered [ 5936.192440] LNet: 122802:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5936.200220] LNetError: 122802:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5937.257508] LNet: Removed LNI 192.168.201.20@tcp [ 5938.125219] Key type .llcrypt unregistered [ 5938.126990] Key type ._llcrypt unregistered [ 5958.718308] Key type ._llcrypt registered [ 5958.720924] Key type .llcrypt registered [ 5960.452813] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 10:20:38 (1789136438) [ 5963.591797] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5963.618730] alg: No test for adler32 (adler32-zlib) [ 5964.730869] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [ 5965.071809] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 5966.864214] Key type lgssc registered [ 5968.271721] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6030.739185] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6037.728511] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6061.793787] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6073.440306] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 6087.659836] Lustre: Unmounted lustre-client [ 6116.879310] Key type lgssc unregistered [ 6117.163708] LNet: 126405:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6117.170091] LNetError: 126405:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6117.200286] LNet: Removed LNI 192.168.201.20@tcp [ 6117.829106] Key type .llcrypt unregistered [ 6117.836918] Key type ._llcrypt unregistered [ 6135.654726] Key type ._llcrypt registered [ 6135.659476] Key type .llcrypt registered [ 6137.506424] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 10:23:35 (1789136615) [ 6140.675431] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6140.944914] alg: No test for adler32 (adler32-zlib) [ 6142.143796] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [ 6142.413816] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 6144.144204] Key type lgssc registered [ 6145.736301] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6204.097495] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6211.410645] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6237.151464] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6238.923154] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 6244.338117] Lustre: lustre-OST0000-osc-ffff8ec846fb5000: Connection to lustre-OST0000 (at 192.168.201.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6257.367535] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff8ec846fb5000.ost_server_uuid 50 [ 6259.427587] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 6270.066170] Lustre: Unmounted lustre-client [ 6292.855620] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 10:26:11 (1789136771) [ 6335.803990] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6342.528895] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6367.857039] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6394.940646] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6395.531944] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 6401.019722] Lustre: lustre-OST0000-osc-ffff8ec84491b000: Connection to lustre-OST0000 (at 192.168.201.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6431.639038] Lustre: Unmounted lustre-client [ 6490.249376] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 10:29:28 (1789136968) [ 6529.044263] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6535.418807] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6558.715120] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6584.077236] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6585.225258] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 6590.449927] Lustre: lustre-OST0000-osc-ffff8ec846355000: Connection to lustre-OST0000 (at 192.168.201.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6608.214666] Lustre: Unmounted lustre-client [ 6657.048085] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 10:32:15 (1789137135) [ 6757.866885] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6763.783099] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6787.309351] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6857.719247] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6864.159339] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6889.213341] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6922.758939] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 6964.662720] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6969.551834] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 3 sec [ 6972.159900] Lustre: Unmounted lustre-client [ 7004.134310] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 10:38:02 (1789137482) [ 7038.687462] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7044.244568] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7065.954281] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7103.565774] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7137.755330] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 7182.824618] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7184.625197] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7206.225320] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7216.541036] Lustre: Unmounted lustre-client [ 7275.098214] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 10:42:33 (1789137753) [ 7310.812307] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7316.847812] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7338.361452] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7339.161977] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 7366.345781] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7368.432631] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7388.113219] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8ec8477be000.ost_server_uuid 50 [ 7389.597557] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8ec8477be000.ost_server_uuid in IDLE state after 0 sec [ 7393.282507] Lustre: Unmounted lustre-client [ 7393.612657] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 7393.625197] Lustre: Skipped 3 previous similar messages [ 7393.708411] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 7394.929799] LustreError: 149868:0:(sec.c:403:import_sec_validate_get()) import 000000005a16838b (NEW) with no sec [ 7458.457674] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 10:45:36 (1789137936) [ 7500.704432] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7507.646266] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7535.212175] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7562.084931] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7562.778042] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 7562.912261] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 7578.895580] Lustre: Unmounted lustre-client [ 7578.906710] Lustre: Skipped 1 previous similar message [ 7637.394798] Key type lgssc unregistered [ 7637.573485] LNet: 153298:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7637.585150] LNetError: 153298:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7638.639831] LNet: Removed LNI 192.168.201.20@tcp [ 7639.582223] Key type .llcrypt unregistered [ 7639.584077] Key type ._llcrypt unregistered [ 7659.011917] Key type ._llcrypt registered [ 7659.016028] Key type .llcrypt registered [ 7660.491652] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 10:48:58 (1789138138) [ 7662.043305] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7662.131147] alg: No test for adler32 (adler32-zlib) [ 7663.407024] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [ 7663.735518] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 7665.513047] Key type lgssc registered [ 7667.080190] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7726.970713] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7733.283372] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7754.061766] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7776.257726] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7778.213530] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7779.490351] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 7787.051631] LustreError: lustre-MDT0001-mdc-ffff8ec850828800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 7787.086444] Lustre: lustre-MDT0001-mdc-ffff8ec850828800: Connection restored to 192.168.201.120@tcp (at 192.168.201.120@tcp) [ 7793.953863] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7795.655049] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7818.254135] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7828.295551] Lustre: Unmounted lustre-client [ 7858.284651] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 10:52:16 (1789138336) [ 7970.268941] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7976.941886] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8003.815769] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8004.746746] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 8032.812420] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8034.386370] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 8047.304349] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 8057.635830] Lustre: Unmounted lustre-client [ 8094.730810] Key type lgssc unregistered [ 8095.013197] LNet: 162921:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8095.030901] LNetError: 162921:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8095.057432] LNet: Removed LNI 192.168.201.20@tcp [ 8095.963465] Key type .llcrypt unregistered [ 8095.966337] Key type ._llcrypt unregistered [ 8130.944806] Key type ._llcrypt registered [ 8130.946978] Key type .llcrypt registered [ 8132.496483] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 10:56:50 (1789138610) [ 8135.219885] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8135.263196] alg: No test for adler32 (adler32-zlib) [ 8136.426732] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [ 8136.755804] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 8138.504368] Key type lgssc registered [ 8139.978396] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8193.926248] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8200.032076] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8221.970391] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8224.683753] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 8274.326640] Lustre: Unmounted lustre-client [ 8320.067323] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8320.709439] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 8357.021838] Lustre: Unmounted lustre-client [ 8388.031680] Key type lgssc unregistered [ 8388.376281] LNet: 167392:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8388.393174] LNetError: 167392:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8388.424503] LNet: Removed LNI 192.168.201.20@tcp [ 8389.333371] Key type .llcrypt unregistered [ 8389.336931] Key type ._llcrypt unregistered [ 8407.261792] Key type ._llcrypt registered [ 8407.264878] Key type .llcrypt registered [ 8408.758989] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 11:01:27 (1789138887) [ 8411.777610] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8411.796509] alg: No test for adler32 (adler32-zlib) [ 8412.976588] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [ 8413.238130] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 8414.920178] Key type lgssc registered [ 8416.025207] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8474.329339] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8482.924693] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8508.881589] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8510.828896] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 8534.737076] Lustre: Unmounted lustre-client [ 8566.653784] Key type lgssc unregistered [ 8566.911836] LNet: 171155:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8566.925616] LNetError: 171155:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8566.958345] LNet: Removed LNI 192.168.201.20@tcp [ 8568.040702] Key type .llcrypt unregistered [ 8568.042832] Key type ._llcrypt unregistered [ 8584.711632] Key type ._llcrypt registered [ 8584.713150] Key type .llcrypt registered [ 8585.512200] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8585.532898] alg: No test for adler32 (adler32-zlib) [ 8586.750988] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [ 8587.013943] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 8588.752186] Key type lgssc registered [ 8590.683075] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8651.531556] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8658.871591] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8684.313894] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8695.385600] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 8705.255602] Lustre: Unmounted lustre-client [ 8735.665985] Key type lgssc unregistered [ 8735.964605] LNet: 174225:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8735.970941] LNetError: 174225:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8735.988517] LNet: Removed LNI 192.168.201.20@tcp [ 8736.712451] Key type .llcrypt unregistered [ 8736.728232] Key type ._llcrypt unregistered [ 8756.383517] Key type ._llcrypt registered [ 8756.385535] Key type .llcrypt registered [ 8757.879345] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 11:07:16 (1789139236) [ 8760.855101] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8761.032285] alg: No test for adler32 (adler32-zlib) [ 8762.249966] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [ 8762.561519] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 8764.264225] Key type lgssc registered [ 8766.125157] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8830.715811] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8838.429874] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8865.570938] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8867.660947] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 8887.591878] Lustre: Unmounted lustre-client [ 8919.288441] Key type lgssc unregistered [ 8919.614854] LNet: 177986:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8919.621366] LNetError: 177986:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8919.645818] LNet: Removed LNI 192.168.201.20@tcp [ 8920.691829] Key type .llcrypt unregistered [ 8920.694167] Key type ._llcrypt unregistered [ 8937.542479] Key type ._llcrypt registered [ 8937.555333] Key type .llcrypt registered [ 8938.083734] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8938.093141] alg: No test for adler32 (adler32-zlib) [ 8939.120939] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [ 8939.447359] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 8941.152205] Key type lgssc registered [ 8942.684238] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9006.226780] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9013.667361] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9046.374562] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9049.294646] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 9057.886054] Lustre: Unmounted lustre-client [ 9086.551694] Key type lgssc unregistered [ 9086.896922] LNet: 181054:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9086.904386] LNetError: 181054:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9086.949362] LNet: Removed LNI 192.168.201.20@tcp [ 9087.768095] Key type .llcrypt unregistered [ 9087.770162] Key type ._llcrypt unregistered [ 9107.631750] Key type ._llcrypt registered [ 9107.634412] Key type .llcrypt registered [ 9109.456700] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 11:13:07 (1789139587) [ 9135.991224] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9136.023895] alg: No test for adler32 (adler32-zlib) [ 9137.294895] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [ 9137.780270] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 9139.696676] Key type lgssc registered [ 9141.546606] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9240.837833] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9249.919284] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9279.876100] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9336.638725] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 11:16:55 (1789139815) [ 9379.225688] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9389.731808] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9418.337712] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9420.138692] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 9425.978781] Lustre: Unmounted lustre-client [ 9459.038641] Key type lgssc unregistered [ 9459.369983] LNet: 188173:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9459.380351] LNetError: 188173:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9459.401215] LNet: Removed LNI 192.168.201.20@tcp [ 9460.252505] Key type .llcrypt unregistered [ 9460.254672] Key type ._llcrypt unregistered [ 9480.869953] Key type ._llcrypt registered [ 9480.871594] Key type .llcrypt registered [ 9482.631423] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 9485.134878] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 11:19:23 (1789139963) [ 9508.488446] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9508.694923] alg: No test for adler32 (adler32-zlib) [ 9509.873269] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [ 9510.159822] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [ 9511.904310] Key type lgssc registered [ 9513.650047] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9624.548178] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9632.212676] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9657.306282] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9682.046653] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9683.908427] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [ 9710.016523] Lustre: Unmounted lustre-client [ 9844.127336] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9850.274131] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9877.810472] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9922.985508] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 11:26:41 (1789140401) [ 9924.837360] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 9926.691630] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 11:26:45 (1789140405) [10034.498624] Key type lgssc unregistered [10034.939547] LNet: 199633:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10034.957203] LNetError: 199633:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10034.984769] LNet: Removed LNI 192.168.201.20@tcp [10035.978829] Key type .llcrypt unregistered [10035.981057] Key type ._llcrypt unregistered [10053.773396] Key type ._llcrypt registered [10053.777855] Key type .llcrypt registered [10055.189724] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 11:28:53 (1789140533) [10100.107402] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10100.121738] alg: No test for adler32 (adler32-zlib) [10101.294244] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [10101.680509] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [10103.432320] Key type lgssc registered [10104.913675] Lustre: Echo OBD driver; http://www.lustre.org/ [10156.803450] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10165.134955] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10203.548847] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10231.863829] Key type lgssc unregistered [10232.115801] LNet: 203372:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10232.120454] LNetError: 203372:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10232.132143] LNet: Removed LNI 192.168.201.20@tcp [10233.041730] Key type .llcrypt unregistered [10233.051196] Key type ._llcrypt unregistered [10254.467073] Key type ._llcrypt registered [10254.472975] Key type .llcrypt registered [10256.089357] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 11:32:14 (1789140734) [10258.783831] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10258.950378] alg: No test for adler32 (adler32-zlib) [10260.160530] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [10260.503745] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [10262.272302] Key type lgssc registered [10263.732850] Lustre: Echo OBD driver; http://www.lustre.org/ [10318.067781] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10325.487758] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10351.216923] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10354.025637] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [10364.398275] Lustre: lustre-MDT0000-mdc-ffff8ec846fb2800: Connection to lustre-MDT0000 (at 192.168.201.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [10374.637681] Lustre: lustre-MDT0001-mdc-ffff8ec846fb2800: Connection to lustre-MDT0001 (at 192.168.201.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [10384.864332] Lustre: 204362:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789140849/real 1789140849] req@00000000f0efa6a5 x1876050040668288/t0(0) o400->MGC192.168.201.120@tcp@192.168.201.120@tcp:26/25 lens 224/224 e 0 to 1 dl 1789140865 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10384.903496] LustreError: MGC192.168.201.120@tcp: Connection to MGS (at 192.168.201.120@tcp) was lost; in progress operations using this service will fail [10395.182273] Lustre: Evicted from MGS (at 192.168.201.120@tcp) after server handle changed from 0xa9cc67140922f582 to 0xa9cc671409231189 [10395.219375] Lustre: MGC192.168.201.120@tcp: Connection restored to 192.168.201.120@tcp (at 192.168.201.120@tcp) [10424.311459] LustreError: lustre-MDT0000-mdc-ffff8ec846fb2800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [10424.343082] Lustre: lustre-MDT0000-mdc-ffff8ec846fb2800: Connection restored to 192.168.201.120@tcp (at 192.168.201.120@tcp) [10430.743207] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10432.268928] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10439.143534] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10441.318647] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [10445.022293] Lustre: Unmounted lustre-client [10478.934220] Key type lgssc unregistered [10479.195608] LNet: 208321:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10479.204340] LNetError: 208321:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10479.226836] LNet: Removed LNI 192.168.201.20@tcp [10480.105110] Key type .llcrypt unregistered [10480.107223] Key type ._llcrypt unregistered [10497.337458] Key type ._llcrypt registered [10497.342584] Key type .llcrypt registered [10498.791115] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 11:36:17 (1789140977) [10501.925829] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10501.951642] alg: No test for adler32 (adler32-zlib) [10503.186783] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [10503.512269] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [10505.233878] Key type lgssc registered [10506.442743] Lustre: Echo OBD driver; http://www.lustre.org/ [10564.290876] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10570.962881] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10641.104880] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10648.030786] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10684.511368] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10707.646456] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10749.237795] Key type lgssc unregistered [10749.607266] LNet: 213622:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10749.617982] LNetError: 213622:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10749.639839] LNet: Removed LNI 192.168.201.20@tcp [10750.307703] Key type .llcrypt unregistered [10750.312840] Key type ._llcrypt unregistered [10797.110308] Key type ._llcrypt registered [10797.113360] Key type .llcrypt registered [10798.732587] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 11:41:17 (1789141277) [10845.234609] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10845.271174] alg: No test for adler32 (adler32-zlib) [10846.381481] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [10846.681274] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [10848.400296] Key type lgssc registered [10849.914845] Lustre: Echo OBD driver; http://www.lustre.org/ [10937.350750] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10942.818412] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10969.031593] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11013.623741] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 11:44:52 (1789141492) [11021.326906] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 11:45:00 (1789141500) [11060.660299] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11067.091934] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11090.838568] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11102.784809] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [11109.689936] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [11112.658203] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [11115.196320] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [11117.727826] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [11123.178888] Lustre: lustre-MDT0000-mdc-ffff8ec86039b800: Connection to lustre-MDT0000 (at 192.168.201.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [11139.557185] Lustre: 215520:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789141603/real 1789141603] req@00000000c1559985 x1876050655062400/t0(0) o400->MGC192.168.201.120@tcp@192.168.201.120@tcp:26/25 lens 224/224 e 0 to 1 dl 1789141619 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [11139.625969] LustreError: MGC192.168.201.120@tcp: Connection to MGS (at 192.168.201.120@tcp) was lost; in progress operations using this service will fail [11149.804925] Lustre: Evicted from MGS (at 192.168.201.120@tcp) after server handle changed from 0xe16c9fcefe49a7ac to 0xe16c9fcefe49aa22 [11149.820461] Lustre: MGC192.168.201.120@tcp: Connection restored to 192.168.201.120@tcp (at 192.168.201.120@tcp) [11153.217857] Lustre: lustre-MDT0000-mdc-ffff8ec86039b800: Connection restored to 192.168.201.120@tcp (at 192.168.201.120@tcp) [11163.714366] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11165.297225] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [11169.181904] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [11175.410848] Lustre: lustre-MDT0000-mdc-ffff8ec86039b800: Connection to lustre-MDT0000 (at 192.168.201.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [11191.776213] Lustre: 215522:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789141655/real 1789141655] req@00000000b24ec37d x1876050655071744/t0(0) o400->MGC192.168.201.120@tcp@192.168.201.120@tcp:26/25 lens 224/224 e 0 to 1 dl 1789141671 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [11191.820394] LustreError: MGC192.168.201.120@tcp: Connection to MGS (at 192.168.201.120@tcp) was lost; in progress operations using this service will fail [11201.018791] Lustre: Evicted from MGS (at 192.168.201.120@tcp) after server handle changed from 0xe16c9fcefe49aa22 to 0xe16c9fcefe49ad32 [11201.051440] Lustre: MGC192.168.201.120@tcp: Connection restored to 192.168.201.120@tcp (at 192.168.201.120@tcp) [11204.874118] Lustre: lustre-MDT0000-mdc-ffff8ec86039b800: Connection restored to 192.168.201.120@tcp (at 192.168.201.120@tcp) [11215.773568] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11217.791432] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [11222.506942] Lustre: Unmounted lustre-client [11289.688676] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11296.557723] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11322.164583] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11322.908465] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [11325.824869] Lustre: Unmounted lustre-client [11353.969842] Key type lgssc unregistered [11354.209902] LNet: 226107:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11354.217897] LNetError: 226107:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11354.235889] LNet: Removed LNI 192.168.201.20@tcp [11355.117167] Key type .llcrypt unregistered [11355.120221] Key type ._llcrypt unregistered [11376.866980] Key type ._llcrypt registered [11376.870413] Key type .llcrypt registered [11378.515713] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 11:50:56 (1789141856) [11383.206859] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11383.389070] alg: No test for adler32 (adler32-zlib) [11384.385545] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [11384.648622] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [11386.352396] Key type lgssc registered [11387.914668] Lustre: Echo OBD driver; http://www.lustre.org/ [11450.237179] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11456.567078] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11481.436183] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11487.322254] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [11494.415875] Lustre: Unmounted lustre-client [11565.201940] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11572.283315] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11595.291373] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11596.122961] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [11605.148308] Lustre: Unmounted lustre-client [11669.613377] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11676.662374] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11699.642127] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11700.462190] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [11702.501582] Lustre: Unmounted lustre-client [11739.080511] Key type lgssc unregistered [11739.320712] LNet: 233158:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11739.331054] LNetError: 233158:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11739.356522] LNet: Removed LNI 192.168.201.20@tcp [11740.127720] Key type .llcrypt unregistered [11740.129756] Key type ._llcrypt unregistered [11758.115985] Key type ._llcrypt registered [11758.118747] Key type .llcrypt registered [11759.830455] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 11:57:18 (1789142238) [11763.492904] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11763.527926] alg: No test for adler32 (adler32-zlib) [11764.731262] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [11764.917375] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [11766.617995] Key type lgssc registered [11768.176562] Lustre: Echo OBD driver; http://www.lustre.org/ [11815.701321] Key type lgssc unregistered [11815.907946] LNet: 235484:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11815.919157] LNetError: 235484:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11815.930532] LNet: Removed LNI 192.168.201.20@tcp [11816.350408] Key type .llcrypt unregistered [11816.353390] Key type ._llcrypt unregistered [11848.753099] Key type ._llcrypt registered [11848.754089] Key type .llcrypt registered [11849.493114] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11849.513327] alg: No test for adler32 (adler32-zlib) [11850.710393] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [11850.968688] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [11852.585534] Key type lgssc registered [11854.145200] Lustre: Echo OBD driver; http://www.lustre.org/ [11946.203380] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11953.019388] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11979.991091] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12029.816842] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [12033.019593] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 12:01:50 (1789142510) [12074.355536] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12081.126123] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12105.985294] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12133.212915] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12136.056634] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [12141.048278] Lustre: lustre-OST0001-osc-ffff8ec8453af000: Connection to lustre-OST0001 (at 192.168.201.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [12148.971871] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12159.342487] Lustre: Unmounted lustre-client [12202.147782] Key type lgssc unregistered [12202.481274] LNet: 242620:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12202.486583] LNetError: 242620:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12202.521399] LNet: Removed LNI 192.168.201.20@tcp [12203.344313] Key type .llcrypt unregistered [12203.345622] Key type ._llcrypt unregistered [12234.625087] Key type ._llcrypt registered [12234.630689] Key type .llcrypt registered [12236.034890] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 12:05:14 (1789142714) [12250.762818] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12250.881162] alg: No test for adler32 (adler32-zlib) [12251.942896] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [12252.164995] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [12253.888593] Key type lgssc registered [12255.487574] Lustre: Echo OBD driver; http://www.lustre.org/ [12304.864907] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12311.388000] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12341.847826] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 12:07:00 (1789142820) [12378.254332] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12384.538107] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12407.790798] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12410.577455] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [12422.676090] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [12428.349907] Lustre: Unmounted lustre-client [12543.788573] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12550.123163] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12600.627780] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12601.095172] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [12604.445856] Lustre: Unmounted lustre-client [12634.051913] Key type lgssc unregistered [12634.396348] LNet: 250310:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12634.404157] LNetError: 250310:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12634.423830] LNet: Removed LNI 192.168.201.20@tcp [12635.013407] Key type .llcrypt unregistered [12635.015948] Key type ._llcrypt unregistered [12667.241760] Key type ._llcrypt registered [12667.244737] Key type .llcrypt registered [12667.814372] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12667.826824] alg: No test for adler32 (adler32-zlib) [12668.829871] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [12669.038137] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [12670.696189] Key type lgssc registered [12671.986613] Lustre: Echo OBD driver; http://www.lustre.org/ [12757.252243] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12763.081600] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12794.019883] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12839.880780] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [12841.822696] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 12:15:20 (1789143320) [12878.058234] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12884.544472] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12910.019642] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12914.972462] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [12919.060954] Lustre: Unmounted lustre-client [12948.698832] Key type lgssc unregistered [12949.029228] LNet: 256950:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12949.047771] LNetError: 256950:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12949.069886] LNet: Removed LNI 192.168.201.20@tcp [12949.952347] Key type .llcrypt unregistered [12949.956884] Key type ._llcrypt unregistered [12968.020619] Key type ._llcrypt registered [12968.022778] Key type .llcrypt registered [12969.507132] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [12971.133194] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 12:17:29 (1789143449) [12981.604554] Key type .llcrypt unregistered [12981.608624] Key type ._llcrypt unregistered [12993.854684] Key type ._llcrypt registered [12993.866896] Key type .llcrypt registered [12994.858123] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12994.908367] alg: No test for adler32 (adler32-zlib) [12996.253767] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [12996.623877] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [12998.376262] Key type lgssc registered [12999.765750] Lustre: Echo OBD driver; http://www.lustre.org/ [13059.858870] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13078.845506] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [13083.531098] Lustre: Unmounted lustre-client [13108.963461] Key type lgssc unregistered [13109.275351] LNet: 260412:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13109.283383] LNetError: 260412:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13109.308611] LNet: Removed LNI 192.168.201.20@tcp [13110.241194] Key type .llcrypt unregistered [13110.242516] Key type ._llcrypt unregistered [13128.230970] Key type ._llcrypt registered [13128.238216] Key type .llcrypt registered [13129.692300] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 12:20:08 (1789143608) [13131.956088] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13131.985059] alg: No test for adler32 (adler32-zlib) [13133.080234] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [13133.309208] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [13134.952231] Key type lgssc registered [13136.045610] Lustre: Echo OBD driver; http://www.lustre.org/ [13218.551673] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13252.426819] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13260.331541] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13263.011533] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [13264.633240] Lustre: Unmounted lustre-client [13287.508345] Key type lgssc unregistered [13287.743052] LNet: 263957:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13287.747282] LNetError: 263957:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13287.763555] LNet: Removed LNI 192.168.201.20@tcp [13288.567735] Key type .llcrypt unregistered [13288.573461] Key type ._llcrypt unregistered [13302.304603] Key type ._llcrypt registered [13302.307313] Key type .llcrypt registered [13303.228492] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 12:23:02 (1789143782) [13304.759100] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13304.773690] alg: No test for adler32 (adler32-zlib) [13305.843972] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [13306.072884] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [13307.712240] Key type lgssc registered [13308.782973] Lustre: Echo OBD driver; http://www.lustre.org/ [13368.235617] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13374.480546] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13395.576213] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13397.121687] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [13402.092903] Lustre: lustre-MDT0000-mdc-ffff8ec8580e1800: Connection to lustre-MDT0000 (at 192.168.201.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [13408.506584] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [13408.547950] LustreError: 267120:0:(file.c:6184:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [13409.075212] LustreError: 267139:0:(file.c:6184:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [13409.081880] LustreError: 267139:0:(file.c:6184:ll_inode_revalidate_fini()) Skipped 5 previous similar messages [13409.152121] LustreError: MGC192.168.201.120@tcp: Connection to MGS (at 192.168.201.120@tcp) was lost; in progress operations using this service will fail [13409.196817] Lustre: Unmounted lustre-client [13430.225466] Key type lgssc unregistered [13430.445855] LNet: 267536:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13430.451623] LNetError: 267536:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13430.476640] LNet: Removed LNI 192.168.201.20@tcp [13431.125143] Key type .llcrypt unregistered [13431.128222] Key type ._llcrypt unregistered [13448.282843] Key type ._llcrypt registered [13448.288566] Key type .llcrypt registered [13449.585947] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 12:25:28 (1789143928) [13452.120940] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13452.139778] alg: No test for adler32 (adler32-zlib) [13453.353424] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [13453.658495] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [13455.369129] Key type lgssc registered [13456.562318] Lustre: Echo OBD driver; http://www.lustre.org/ [13512.762690] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13518.747365] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13547.423247] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13550.208341] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [13555.181194] Lustre: lustre-MDT0001-mdc-ffff8ec860392000: Connection to lustre-MDT0001 (at 192.168.201.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [13558.367365] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [13559.679877] Lustre: Unmounted lustre-client [13582.607807] Key type lgssc unregistered [13582.878987] LNet: 271123:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13582.897870] LNetError: 271123:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13582.917588] LNet: Removed LNI 192.168.201.20@tcp [13584.009307] Key type .llcrypt unregistered [13584.011836] Key type ._llcrypt unregistered [13603.507843] Key type ._llcrypt registered [13603.511353] Key type .llcrypt registered [13605.297152] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 12:28:03 (1789144083) [13626.742317] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13626.864979] alg: No test for adler32 (adler32-zlib) [13627.935945] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [13628.320649] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [13630.088188] Key type lgssc registered [13631.706451] Lustre: Echo OBD driver; http://www.lustre.org/ [13755.930922] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13757.695337] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [13805.555509] Lustre: lustre-MDT0001-mdc-ffff8ec851225000: Connection to lustre-MDT0001 (at 192.168.201.120@tcp) was lost; in progress operations using this service will wait for recovery to complete [13822.317631] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [13832.778686] Lustre: Unmounted lustre-client [13865.312411] Key type lgssc unregistered [13865.446767] LNet: 276921:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13865.450068] LNetError: 276921:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13865.460730] LNet: Removed LNI 192.168.201.20@tcp [13865.814437] Key type .llcrypt unregistered [13865.817675] Key type ._llcrypt unregistered [13886.703523] Key type ._llcrypt registered [13886.705534] Key type .llcrypt registered [13888.494930] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 12:32:46 (1789144366) [13890.410649] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [13892.619944] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 12:32:51 (1789144371) [13894.733909] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [13896.864323] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 12:32:55 (1789144375) [13898.844171] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [13901.394654] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 12:32:59 (1789144379) [13903.528329] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [13905.951081] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 12:33:04 (1789144384) [13908.317458] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [13910.442806] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 12:33:08 (1789144388) [13921.276516] Key type .llcrypt unregistered [13921.279771] Key type ._llcrypt unregistered [13931.583328] Key type ._llcrypt registered [13931.619618] Key type .llcrypt registered [13932.106806] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13932.130685] alg: No test for adler32 (adler32-zlib) [13933.397148] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [13933.778643] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [13935.576196] Key type lgssc registered [13937.351590] Lustre: Echo OBD driver; http://www.lustre.org/ [14025.308319] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14032.556921] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14059.990408] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14061.816942] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [14065.264861] Lustre: Unmounted lustre-client [14106.579325] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 12:36:25 (1789144585) [14151.290943] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14158.449989] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14196.961818] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14198.036918] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [14200.868472] Lustre: Unmounted lustre-client [14246.955845] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 12:38:45 (1789144725) [14248.160384] LNet: 1 local NIs in recovery (showing 1): 192.168.201.20@tcp [14902.067552] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14907.593720] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14941.869080] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15046.002575] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 12:52:04 (1789145524) [15056.653167] Key type lgssc unregistered [15056.848868] LNet: 290462:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15056.858504] LNetError: 290462:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15056.869200] LNet: Removed LNI 192.168.201.20@tcp [15057.403579] Key type .llcrypt unregistered [15057.404963] Key type ._llcrypt unregistered [15194.485950] Key type ._llcrypt registered [15194.511502] Key type .llcrypt registered [15194.966270] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15194.977725] alg: No test for adler32 (adler32-zlib) [15196.168515] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [15196.478463] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [15198.177478] Key type lgssc registered [15199.551590] Lustre: Echo OBD driver; http://www.lustre.org/ [15299.446270] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15305.911974] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15332.525755] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15367.198843] Key type lgssc unregistered [15367.431020] LNet: 294172:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15367.434986] LNetError: 294172:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15367.450372] LNet: Removed LNI 192.168.201.20@tcp [15368.352459] Key type .llcrypt unregistered [15368.355762] Key type ._llcrypt unregistered [15407.973614] Key type ._llcrypt registered [15407.975298] Key type .llcrypt registered [15409.639742] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 12:58:07 (1789145887) [15436.622858] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15436.754268] alg: No test for adler32 (adler32-zlib) [15438.052977] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [15438.547950] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [15440.448221] Key type lgssc registered [15442.102801] Lustre: Echo OBD driver; http://www.lustre.org/ [15500.104465] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 12:59:38 (1789145978) [15511.791056] Key type lgssc unregistered [15512.010346] LNet: 297735:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15512.020653] LNetError: 297735:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15512.038043] LNet: Removed LNI 192.168.201.20@tcp [15512.847750] Key type .llcrypt unregistered [15512.851580] Key type ._llcrypt unregistered [15527.702860] Key type ._llcrypt registered [15527.712652] Key type .llcrypt registered [15528.449832] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15528.467915] alg: No test for adler32 (adler32-zlib) [15529.507613] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [15529.934042] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [15531.696238] Key type lgssc registered [15533.192342] Lustre: Echo OBD driver; http://www.lustre.org/ [15596.941701] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15603.048204] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15627.881659] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15949.081727] LNet: Added LNI 192.168.201.20@tcp50 [8/256/0/180] [15952.210372] LNet: Removed LNI 192.168.201.20@tcp [15954.934733] Lustre: 302164:0:(ldlm_lib.c:599:client_obd_setup()) lustre-MDT0000-mdc-ffff8ec845383800: config has no valid NIDs, force dynamic NIDs [15956.233171] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [15956.458489] Lustre: Unmounted lustre-client [15960.175247] Key type lgssc unregistered [15960.768452] LNet: 302430:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15960.783402] LNetError: 302430:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15961.834980] LNet: Removed LNI 192.168.201.20@tcp50 [15962.842723] Key type .llcrypt unregistered [15962.857319] Key type ._llcrypt unregistered [15983.505359] Key type ._llcrypt registered [15983.519538] Key type .llcrypt registered [15984.310731] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15984.349122] alg: No test for adler32 (adler32-zlib) [15985.898883] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [15986.803414] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [15988.768306] Key type lgssc registered [15989.740564] Lustre: Echo OBD driver; http://www.lustre.org/ [16052.779782] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16058.810427] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16066.288111] LNet: Added LNI 192.168.201.20@tcp50 [8/256/0/180] [16067.184376] LNet: Removed LNI 192.168.201.20@tcp [16068.485907] Lustre: 305125:0:(ldlm_lib.c:599:client_obd_setup()) lustre-MDT0000-mdc-ffff8ec846f25800: config has no valid NIDs, force dynamic NIDs [16069.783201] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [16070.515609] Lustre: Unmounted lustre-client [16106.056627] Key type lgssc unregistered [16106.516847] LNet: 306000:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16106.533499] LNetError: 306000:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16106.574107] LNet: Removed LNI 192.168.201.20@tcp50 [16107.347261] Key type .llcrypt unregistered [16107.351408] Key type ._llcrypt unregistered [16121.642245] Key type ._llcrypt registered [16121.648255] Key type .llcrypt registered [16123.319059] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 13:10:01 (1789146601) [16145.147029] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16145.188248] alg: No test for adler32 (adler32-zlib) [16146.357632] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [16146.671345] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [16148.408186] Key type lgssc registered [16149.778607] Lustre: Echo OBD driver; http://www.lustre.org/ [16248.173093] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16254.745907] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16282.400511] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16325.461967] Key type lgssc unregistered [16325.806922] LNet: 310752:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16325.820372] LNetError: 310752:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16325.855902] LNet: Removed LNI 192.168.201.20@tcp [16326.429852] Key type .llcrypt unregistered [16326.434539] Key type ._llcrypt unregistered [16338.904347] Key type ._llcrypt registered [16338.908406] Key type .llcrypt registered [16340.494822] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 13:13:38 (1789146818) [16343.088710] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16343.151841] alg: No test for adler32 (adler32-zlib) [16344.384209] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [16344.612371] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [16346.345929] Key type lgssc registered [16347.937897] Lustre: Echo OBD driver; http://www.lustre.org/ [16403.213085] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16409.043987] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16431.745374] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16434.403417] Lustre: Mounted lustre-client - version 2.17.57_132_gd76390e [16498.400280] Lustre: Mounted fs15246-client - version 2.17.57_132_gd76390e [16514.516477] Lustre: Unmounted fs15246-client [16541.142994] Lustre: Unmounted lustre-client [16578.275499] Key type lgssc unregistered [16578.511748] LNet: 315614:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16578.518714] LNetError: 315614:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16578.543108] LNet: Removed LNI 192.168.201.20@tcp [16579.288799] Key type .llcrypt unregistered [16579.292844] Key type ._llcrypt unregistered [16594.297407] Key type ._llcrypt registered [16594.303630] Key type .llcrypt registered [16595.846949] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 13:17:54 (1789147074) [16597.864111] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16597.886319] alg: No test for adler32 (adler32-zlib) [16599.029911] Lustre: Lustre: Build Version: 2.17.57_132_gd76390e [16599.345095] LNet: Added LNI 192.168.201.20@tcp [8/256/0/180] [16601.064238] Key type lgssc registered [16602.787547] Lustre: Echo OBD driver; http://www.lustre.org/ [16762.383668] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16768.957367] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16798.150296] Lustre: DEBUG MARKER: oleg120-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16938.574585] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 16649 sec ========= 13:23:36 (1789147416) [16941.116527] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 13:23:39 (1789147419) === [16945.811773] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 13:23:43 (1789147423) === [16986.673181] Key type lgssc unregistered [16986.935947] LNet: 324895:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16986.942538] LNetError: 324895:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16986.961542] LNet: Removed LNI 192.168.201.20@tcp [16987.889580] Key type .llcrypt unregistered [16987.892386] Key type ._llcrypt unregistered