[ 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 503743009 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001011] APIC: Switch to symmetric I/O mode setup [ 0.002316] x2apic enabled [ 0.003006] Switched APIC routing to physical x2apic. [ 0.004014] kvm-guest: setup PV IPIs [ 0.007405] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008020] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009010] pid_max: default: 32768 minimum: 301 [ 0.010146] LSM: Security Framework initializing [ 0.011078] Yama: becoming mindful. [ 0.012039] SELinux: Initializing. [ 0.013062] *** VALIDATE selinux *** [ 0.021712] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026337] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.027144] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028112] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029098] *** VALIDATE tmpfs *** [ 0.030398] *** VALIDATE proc *** [ 0.032132] *** VALIDATE cgroup *** [ 0.033009] *** VALIDATE cgroup2 *** [ 0.035181] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.036153] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.037008] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.038028] Spectre V2 : User space: Vulnerable [ 0.039008] Speculative Store Bypass: Vulnerable [ 0.041807] debug: unmapping init [mem 0xffffffff94859000-0xffffffff94860fff] [ 0.043198] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.044677] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.045022] ... version: 2 [ 0.046011] ... bit width: 48 [ 0.047012] ... generic registers: 4 [ 0.048008] ... value mask: 0000ffffffffffff [ 0.049013] ... max period: 00007fffffffffff [ 0.050015] ... fixed-purpose events: 3 [ 0.051013] ... event mask: 000000070000000f [ 0.053228] rcu: Hierarchical SRCU implementation. [ 0.055379] smp: Bringing up secondary CPUs ... [ 0.056577] x86: Booting SMP configuration: [ 0.057025] .... node #0, CPUs: #1 #2 #3 [ 0.060597] smp: Brought up 1 node, 4 CPUs [ 0.062014] smpboot: Max logical packages: 1 [ 0.063027] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.239263] node 0 deferred pages initialised in 174ms [ 0.243231] devtmpfs: initialized [ 0.245376] x86/mm: Memory block size: 128MB [ 0.249469] gcov: version magic: 0x41383552 [ 0.252393] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.256164] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.259468] pinctrl core: initialized pinctrl subsystem [ 0.262255] [ 0.262921] ************************************************************* [ 0.265019] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.267016] ** ** [ 0.270019] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.273019] ** ** [ 0.276168] ** This means that this kernel is built to expose internal ** [ 0.278019] ** IOMMU data structures, which may compromise security on ** [ 0.281020] ** your system. ** [ 0.283013] ** ** [ 0.285018] ** If you see this message and you are not debugging the ** [ 0.288017] ** kernel, report this immediately to your vendor! ** [ 0.290014] ** ** [ 0.292092] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.295014] ************************************************************* [ 0.297952] NET: Registered protocol family 16 [ 0.300653] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.303080] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.306084] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.310017] cpuidle: using governor menu [ 0.311564] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.315071] PCI: Using configuration type 1 for base access [ 0.317152] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.327106] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.330009] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.334109] cryptd: max_cpu_qlen set to 1000 [ 0.336192] ACPI: Added _OSI(Module Device) [ 0.337018] ACPI: Added _OSI(Processor Device) [ 0.338052] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.340020] ACPI: Added _OSI(Processor Aggregator Device) [ 0.347681] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.355672] ACPI: Interpreter enabled [ 0.357264] ACPI: PM: (supports S0 S3 S4 S5) [ 0.359035] ACPI: Using IOAPIC for interrupt routing [ 0.360127] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.364399] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.373945] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.376043] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.379024] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.384090] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.389372] acpiphp: Slot [2] registered [ 0.390177] acpiphp: Slot [5] registered [ 0.392172] acpiphp: Slot [6] registered [ 0.393140] acpiphp: Slot [3] registered [ 0.395099] acpiphp: Slot [4] registered [ 0.397103] acpiphp: Slot [7] registered [ 0.398157] acpiphp: Slot [8] registered [ 0.400158] acpiphp: Slot [9] registered [ 0.402179] acpiphp: Slot [10] registered [ 0.404144] acpiphp: Slot [11] registered [ 0.406202] acpiphp: Slot [12] registered [ 0.407162] acpiphp: Slot [13] registered [ 0.409151] acpiphp: Slot [14] registered [ 0.411104] acpiphp: Slot [15] registered [ 0.412104] acpiphp: Slot [16] registered [ 0.414137] acpiphp: Slot [17] registered [ 0.416113] acpiphp: Slot [18] registered [ 0.417211] acpiphp: Slot [19] registered [ 0.419108] acpiphp: Slot [20] registered [ 0.421170] acpiphp: Slot [21] registered [ 0.422168] acpiphp: Slot [22] registered [ 0.424121] acpiphp: Slot [23] registered [ 0.426105] acpiphp: Slot [24] registered [ 0.427195] acpiphp: Slot [25] registered [ 0.429108] acpiphp: Slot [26] registered [ 0.431185] acpiphp: Slot [27] registered [ 0.432163] acpiphp: Slot [28] registered [ 0.434127] acpiphp: Slot [29] registered [ 0.436115] acpiphp: Slot [30] registered [ 0.437106] acpiphp: Slot [31] registered [ 0.439106] PCI host bridge to bus 0000:00 [ 0.441024] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.443085] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.446034] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.449027] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.451029] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.454079] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.457223] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.460198] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.464490] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.472019] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.477074] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.481026] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.484017] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.486022] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.489843] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.492961] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.496065] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.499129] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.504066] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.515021] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.519846] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.527000] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.534023] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.541020] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.558026] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.566767] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.573021] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.578018] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.591018] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.603098] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.605442] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.608470] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.610398] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.613227] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.618142] iommu: Default domain type: Passthrough [ 0.620590] SCSI subsystem initialized [ 0.622357] ACPI: bus type USB registered [ 0.624352] usbcore: registered new interface driver usbfs [ 0.627132] usbcore: registered new interface driver hub [ 0.630148] usbcore: registered new device driver usb [ 0.632261] pps_core: LinuxPPS API ver. 1 registered [ 0.635022] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.639102] PTP clock support registered [ 0.641185] EDAC MC: Ver: 3.0.0 [ 0.643260] PCI: Using ACPI for IRQ routing [ 0.645558] NetLabel: Initializing [ 0.647011] NetLabel: domain hash size = 128 [ 0.648008] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.650107] NetLabel: unlabeled traffic allowed by default [ 0.652249] vgaarb: loaded [ 0.654105] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.655015] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.661709] clocksource: Switched to clocksource kvm-clock [ 0.792523] VFS: Disk quotas dquot_6.6.0 [ 0.794378] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.797780] *** VALIDATE ramfs *** [ 0.799014] *** VALIDATE hugetlbfs *** [ 0.800465] pnp: PnP ACPI init [ 0.805403] pnp: PnP ACPI: found 6 devices [ 0.861213] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.865323] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.867822] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.869932] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.872234] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.874401] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.876930] NET: Registered protocol family 2 [ 0.879112] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.884228] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.887706] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.892550] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.895678] TCP: Hash tables configured (established 65536 bind 65536) [ 0.897668] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.900327] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.902979] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.905892] NET: Registered protocol family 1 [ 0.908041] RPC: Registered named UNIX socket transport module. [ 0.910095] RPC: Registered udp transport module. [ 0.911667] RPC: Registered tcp transport module. [ 0.913158] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.915213] NET: Registered protocol family 44 [ 0.916851] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.919102] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.921178] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.923408] PCI: CLS 0 bytes, default 64 [ 0.925037] Unpacking initramfs... [ 2.341907] debug: unmapping init [mem 0xffff95fa7cc64000-0xffff95fa7ffcffff] [ 2.344990] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.347197] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.350165] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.872504] Initialise system trusted keyrings [ 2.874317] Key type blacklist registered [ 2.876487] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.891899] zbud: loaded [ 2.896369] *** VALIDATE nfs *** [ 2.897934] *** VALIDATE nfs4 *** [ 2.900963] pstore: using deflate compression [ 2.904951] Platform Keyring initialized [ 3.022618] NET: Registered protocol family 38 [ 3.024071] Key type asymmetric registered [ 3.025121] Asymmetric key parser 'x509' registered [ 3.026441] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.028511] io scheduler mq-deadline registered [ 3.029654] io scheduler kyber registered [ 3.030951] io scheduler bfq registered [ 3.032399] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.035613] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.038193] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.040970] ACPI: Power Button [PWRF] [ 3.044785] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.050283] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.058739] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.085525] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.113748] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.118242] Non-volatile memory driver v1.3 [ 3.119562] Linux agpgart interface v0.103 [ 3.156424] virtio_blk virtio1: [vda] 145912 512-byte logical blocks (74.7 MB/71.2 MiB) [ 3.160526] vda: detected capacity change from 0 to 74706944 [ 3.177355] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.180471] vdb: detected capacity change from 0 to 1073741824 [ 3.190634] libphy: Fixed MDIO Bus: probed [ 3.208343] usbcore: registered new interface driver usbserial_generic [ 3.210529] usbserial: USB Serial support registered for generic [ 3.212803] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.217294] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.219363] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.221950] mousedev: PS/2 mouse device common for all mice [ 3.226469] rtc_cmos 00:05: RTC can wake from S4 [ 3.233493] rtc_cmos 00:05: registered as rtc0 [ 3.234992] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.236099] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.239381] intel_pstate: CPU model not supported [ 3.245278] hid: raw HID events driver (C) Jiri Kosina [ 3.245513] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.247580] usbcore: registered new interface driver usbhid [ 3.247589] usbhid: USB HID core driver [ 3.249274] drop_monitor: Initializing network drop monitor service [ 3.258761] Initializing XFRM netlink socket [ 3.258942] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.260527] NET: Registered protocol family 10 [ 3.268132] Segment Routing with IPv6 [ 3.269146] NET: Registered protocol family 17 [ 3.270677] mpls_gso: MPLS GSO support [ 3.276722] RAS: Correctable Errors collector initialized. [ 3.278394] AVX version of gcm_enc/dec engaged. [ 3.279748] AES CTR mode by8 optimization enabled [ 3.359926] sched_clock: Marking stable (3359903844, 0)->(4273067342, -913163498) [ 3.363459] registered taskstats version 1 [ 3.365648] Loading compiled-in X.509 certificates [ 3.367946] zswap: loaded using pool lzo/zbud [ 3.399179] Key type big_key registered [ 3.415146] Key type encrypted registered [ 3.416716] ima: No TPM chip found, activating TPM-bypass! [ 3.418374] ima: Allocated hash algorithm: sha1 [ 3.420109] ima: No architecture policies found [ 3.421899] evm: Initialising EVM extended attributes: [ 3.423767] evm: security.selinux [ 3.425193] evm: security.ima [ 3.426390] evm: security.capability [ 3.427892] evm: HMAC attrs: 0x1 [ 3.430311] rtc_cmos 00:05: setting system clock to 2026-08-13 17:17:04 UTC (1786641424) [ 3.437675] debug: unmapping init [mem 0xffffffff95803000-0xffffffff959fffff] [ 3.441050] debug: unmapping init [mem 0xffffffff94582000-0xffffffff94858fff] [ 3.449181] Write protecting the kernel read-only data: 28672k [ 3.452693] debug: unmapping init [mem 0xffffffff92c03000-0xffffffff92dfffff] [ 3.455209] debug: unmapping init [mem 0xffffffff93514000-0xffffffff935fffff] [ 3.489942] 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.498891] systemd[1]: Detected virtualization kvm. [ 3.500666] systemd[1]: Detected architecture x86-64. [ 3.502534] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.528651] systemd[1]: No hostname configured. [ 3.530386] systemd[1]: Set hostname to . [ 3.532745] random: systemd: uninitialized urandom read (16 bytes read) [ 3.535253] systemd[1]: Initializing machine ID from random generator. [ 3.724138] random: systemd: uninitialized urandom read (16 bytes read) [ 3.727096] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 3.733000] random: systemd: uninitialized urandom read (16 bytes read) [ 3.735972] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ 3.745235] systemd[1]: Starting Apply Kernel Variables... Starting Apply Kernel Variables... [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Local Encrypted Volumes. Starting Journal Service... [ OK ] Reached target Paths. Starting Setup Virtual Console... [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Reached target Slices. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on udev Kernel Socket. [ OK ] Started Memstrack Anylazing Service. [ OK ] Reached target Timers. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Swap. [ OK ] Reached target Initrd Root Device. [ OK ] Started Apply Kernel Variables. [ OK ] Started Setup Virtual Console. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Journal Service. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 5.050180] device-mapper: uevent: version 1.0.3 [ 5.061789] 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...[ 6.372991] random: fast init done 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. [ 7.600042] virtio_net virtio0 ens2: renamed from eth0 [ 8.020372] scsi host0: ata_piix [ 8.141748] scsi host1: ata_piix [ 8.146869] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 8.154083] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 13.948211] random: crng init done [ 13.953194] random: 7 urandom warning(s) missed due to ratelimiting [ 14.539935] dracut-initqueue[597]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 17.406524] 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 Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Swap. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 19.799383] printk: systemd: 21 output lines suppressed due to ratelimiting [ 20.865627] SELinux: Disabled at runtime. [ 20.994649] 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) [ 21.024059] systemd[1]: Detected virtualization kvm. [ 21.025938] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 23.089323] systemd[1]: initrd-switch-root.service: Succeeded. [ 23.097330] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 23.115569] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 23.129137] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 23.138153] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 23.174077] systemd[1]: Starting Journal Service... Starting Journal Service... [ 23.204480] systemd[1]: Listening on RPCbind Server Activation Socket. [ OK ] Listening on RPCbind Server Activation Socket. Activating swap /dev/disk/by-label/SWAP... [ OK ] Created slice User and Session Slice. Starting Remount Root and Kernel File Systems... Mounting Kernel Debug File System... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 23.322084] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Mounting Huge Pages File System... [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Reached target Slices. [ OK ] Listening on udev Control Socket. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Created slice system-getty.slice. Mounting POSIX Message Queue File System... Starting Apply Kernel Variables... [ OK ] Listening on Process Core Dump Socket. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target rpc_pipefs.target. [ OK ] Reached target RPC Port Mapper. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Stopped target Initrd Root File System. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Started Journal Service. [ OK ] Activated swap /dev/disk/by-label/SWAP. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started udev Coldplug all Devices. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Mounted /mnt. [ 25.009463] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 25.944893] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 25.946233] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 26.365301] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 26.398790] EDAC sbridge: Ver: 1.1.2 [ 29.716420] Key type dns_resolver registered [ 30.583826] NFS: Registering the id_resolver key type [ 30.602410] Key type id_resolver registered [ 30.615092] Key type id_legacy registered [* ] A start job is running for Configur…-only root support (8s / no limit) [** ] A start job is running for Configur…-only root support (8s / no limit) [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... Starting Mark the need to relabel after reboot... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started dnf makecache --timer. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started irqbalance daemon. [ OK ] Reached target sshd-keygen.target. Starting Restore /run/initramfs on shutdown... [ OK ] Started D-Bus System Message Bus. Starting Login Service... Starting Network Manager... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Started Login Service. Starting Hostname Service... [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Command Scheduler. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg246-client login: [ 91.383457] libcfs: loading out-of-tree module taints kernel. [ 91.613350] Key type ._llcrypt registered [ 91.616298] Key type .llcrypt registered [ 92.323490] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 92.344152] alg: No test for adler32 (adler32-zlib) [ 93.831480] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [ 94.952849] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [ 96.711284] Key type lgssc registered [ 99.072125] Lustre: Echo OBD driver; http://www.lustre.org/ [ 268.460122] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 273.064723] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 286.344566] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing check_logdir /tmp/testlogs/ [ 290.586027] hrtimer: interrupt took 4017694 ns [ 291.065395] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing yml_node [ 294.370169] Lustre: lustre-OST0000-osc-ffff95fad1c41000: disconnect after 24s idle [ 295.022367] Lustre: DEBUG MARKER: Client: 2.17.57.1 [ 297.221787] Lustre: DEBUG MARKER: MDS: 2.17.57.1 [ 299.920298] Lustre: DEBUG MARKER: OSS: 2.17.57.1 [ 301.509489] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Thu Aug 13 13:22:01 EDT 2026 [ 317.318882] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 318.904745] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 321.498918] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 331.880389] Lustre: Unmounted lustre-client [ 443.574295] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 449.758933] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 473.336382] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 520.156789] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 13:25:39 (1786641939) [ 523.295171] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 525.110231] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 13:25:44 (1786641944) [ 560.998683] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 568.229993] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 595.270734] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 595.733273] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 598.847422] Lustre: Unmounted lustre-client [ 627.886063] Key type lgssc unregistered [ 628.158872] LNet: 15054:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 628.178189] LNetError: 15054:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 628.196514] LNet: Removed LNI 192.168.202.46@tcp [ 629.365331] Key type .llcrypt unregistered [ 629.371387] Key type ._llcrypt unregistered [ 646.386573] Key type ._llcrypt registered [ 646.388865] Key type .llcrypt registered [ 647.442551] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 13:27:47 (1786642067) [ 648.782708] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 648.947479] alg: No test for adler32 (adler32-zlib) [ 649.949904] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [ 650.170936] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [ 651.831254] Key type lgssc registered [ 652.830995] Lustre: Echo OBD driver; http://www.lustre.org/ [ 733.451571] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 819.340445] Lustre: Unmounted lustre-client [ 849.454939] Key type lgssc unregistered [ 849.681034] LNet: 18842:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 849.684845] LNetError: 18842:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 849.701439] LNet: Removed LNI 192.168.202.46@tcp [ 850.560617] Key type .llcrypt unregistered [ 850.563368] Key type ._llcrypt unregistered [ 859.574665] Key type ._llcrypt registered [ 859.577893] Key type .llcrypt registered [ 860.943343] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 13:31:20 (1786642280) [ 863.277836] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 863.374866] alg: No test for adler32 (adler32-zlib) [ 864.501384] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [ 864.890509] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [ 866.602895] Key type lgssc registered [ 867.801239] Lustre: Echo OBD driver; http://www.lustre.org/ [ 916.307647] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 921.257786] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 941.858990] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 943.445756] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 951.930054] Lustre: Unmounted lustre-client [ 976.422230] Key type lgssc unregistered [ 976.682514] LNet: 22446:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 976.692698] LNetError: 22446:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 976.710699] LNet: Removed LNI 192.168.202.46@tcp [ 977.437566] Key type .llcrypt unregistered [ 977.440465] Key type ._llcrypt unregistered [ 987.400374] Key type ._llcrypt registered [ 987.401629] Key type .llcrypt registered [ 988.479466] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 13:33:28 (1786642408) [ 990.351394] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 990.384275] alg: No test for adler32 (adler32-zlib) [ 991.444226] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [ 991.661509] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [ 993.303277] Key type lgssc registered [ 994.546067] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1042.266745] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1047.438839] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1068.623947] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1071.526024] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 1079.788073] Lustre: Unmounted lustre-client [ 1108.492460] Key type lgssc unregistered [ 1108.735709] LNet: 25641:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1108.752543] LNetError: 25641:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1108.775977] LNet: Removed LNI 192.168.202.46@tcp [ 1109.757051] Key type .llcrypt unregistered [ 1109.769151] Key type ._llcrypt unregistered [ 1127.638368] Key type ._llcrypt registered [ 1127.640225] Key type .llcrypt registered [ 1128.952691] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 13:35:48 (1786642548) [ 1131.440855] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1131.460531] alg: No test for adler32 (adler32-zlib) [ 1132.522952] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [ 1132.693271] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [ 1134.319966] Key type lgssc registered [ 1135.200417] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1187.266573] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1198.531389] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1220.239711] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1232.493096] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 1237.503168] Lustre: lustre-MDT0000-mdc-ffff95fac4674000: Connection to lustre-MDT0000 (at 192.168.202.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1257.967952] Lustre: lustre-MDT0001-mdc-ffff95fac4674000: Connection to lustre-MDT0001 (at 192.168.202.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1268.191641] Lustre: 26600:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786642673/real 1786642673] req@000000007851c84c x1873430501661824/t0(0) o400->MGC192.168.202.146@tcp@192.168.202.146@tcp:26/25 lens 224/224 e 0 to 1 dl 1786642689 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1268.225173] LustreError: MGC192.168.202.146@tcp: Connection to MGS (at 192.168.202.146@tcp) was lost; in progress operations using this service will fail [ 1272.282588] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1282.604540] Lustre: Unmounted lustre-client [ 1294.478940] Key type lgssc unregistered [ 1294.743508] LNet: 29300:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1294.754180] LNetError: 29300:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1294.767716] LNet: Removed LNI 192.168.202.46@tcp [ 1295.356086] Key type .llcrypt unregistered [ 1295.363760] Key type ._llcrypt unregistered [ 1308.081882] Key type ._llcrypt registered [ 1308.086330] Key type .llcrypt registered [ 1309.165684] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 13:38:49 (1786642729) [ 1311.698695] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1311.722325] alg: No test for adler32 (adler32-zlib) [ 1312.810051] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [ 1313.059672] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [ 1314.775259] Key type lgssc registered [ 1315.849657] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1361.192860] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1366.845798] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1385.122960] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1386.843769] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 1392.105573] Lustre: lustre-OST0000-osc-ffff95fad28a2000: Connection to lustre-OST0000 (at 192.168.202.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1401.012822] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1411.353303] Lustre: Unmounted lustre-client [ 1438.470603] Key type lgssc unregistered [ 1438.776682] LNet: 32913:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1438.780601] LNetError: 32913:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1438.797607] LNet: Removed LNI 192.168.202.46@tcp [ 1439.268427] Key type .llcrypt unregistered [ 1439.269947] Key type ._llcrypt unregistered [ 1457.010657] Key type ._llcrypt registered [ 1457.013679] Key type .llcrypt registered [ 1458.114701] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 13:41:17 (1786642877) [ 1460.338870] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1460.416189] alg: No test for adler32 (adler32-zlib) [ 1461.455377] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [ 1461.633954] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [ 1463.279204] Key type lgssc registered [ 1464.474389] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1523.353069] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1527.932563] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1549.217632] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1552.428874] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 1556.335868] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1560.518309] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1571.915332] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-08-1313h43m11s [ 1574.545129] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1577.972241] Lustre: lustre-MDT0000-mdc-ffff95fad0c8d000: Connection to lustre-MDT0000 (at 192.168.202.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1593.438145] LustreError: 36708:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff95fad0c8d000: can't stat MDS #0: rc = -19 [ 1594.895587] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1606.640226] LustreError: MGC192.168.202.146@tcp: Connection to MGS (at 192.168.202.146@tcp) was lost; in progress operations using this service will fail [ 1606.668955] Lustre: Evicted from MGS (at 192.168.202.146@tcp) after server handle changed from 0xa83328cfda3a9011 to 0xa83328cfda3a92d4 [ 1606.698742] Lustre: MGC192.168.202.146@tcp: Connection restored to 192.168.202.146@tcp (at 192.168.202.146@tcp) [ 1607.724434] LustreError: lustre-MDT0000-mdc-ffff95fad0c8d000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1607.784176] Lustre: lustre-MDT0000-mdc-ffff95fad0c8d000: Connection restored to 192.168.202.146@tcp (at 192.168.202.146@tcp) [ 1612.778661] Lustre: DEBUG MARKER: Wait for df (36708) ... [ 1613.858121] Lustre: DEBUG MARKER: done [ 1616.308728] Lustre: Unmounted lustre-client [ 1642.123863] Key type lgssc unregistered [ 1642.310857] LNet: 37726:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1642.315102] LNetError: 37726:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1642.332694] LNet: Removed LNI 192.168.202.46@tcp [ 1642.813965] Key type .llcrypt unregistered [ 1642.815292] Key type ._llcrypt unregistered [ 1672.264462] Key type ._llcrypt registered [ 1672.268653] Key type .llcrypt registered [ 1673.510681] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 13:44:53 (1786643093) [ 1676.429795] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1676.484765] alg: No test for adler32 (adler32-zlib) [ 1677.622794] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [ 1677.890740] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [ 1679.615179] Key type lgssc registered [ 1680.730461] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1731.075631] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1736.954952] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1756.743937] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1758.368159] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 1762.298408] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-08-1313h46m22s [ 1763.661176] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1769.705639] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1772.032420] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1792.494714] Lustre: 41869:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786643193/real 1786643193] req@00000000335daeba x1873431073134592/t0(0) o101->lustre-MDT0000-mdc-ffff95fad03fb800@192.168.202.146@tcp:12/10 lens 576/1152 e 0 to 1 dl 1786643213 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1792.575909] Lustre: lustre-MDT0000-mdc-ffff95fad03fb800: Connection to lustre-MDT0000 (at 192.168.202.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1792.616874] Lustre: lustre-MDT0000-mdc-ffff95fad03fb800: Connection restored to 192.168.202.146@tcp (at 192.168.202.146@tcp) [ 1815.116616] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1818.764166] Lustre: DEBUG MARKER: done [ 1822.595160] Lustre: Unmounted lustre-client [ 1858.423832] Key type lgssc unregistered [ 1858.749734] LNet: 42753:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1858.758161] LNetError: 42753:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1858.789493] LNet: Removed LNI 192.168.202.46@tcp [ 1859.666779] Key type .llcrypt unregistered [ 1859.669127] Key type ._llcrypt unregistered [ 1895.764853] Key type ._llcrypt registered [ 1895.766963] Key type .llcrypt registered [ 1896.942767] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 13:48:36 (1786643316) [ 1898.462827] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1899.919992] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 13:48:39 (1786643319) [ 1902.927908] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1902.938125] alg: No test for adler32 (adler32-zlib) [ 1903.968641] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [ 1904.234705] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [ 1905.912415] Key type lgssc registered [ 1907.077874] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1941.041385] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 13:49:20 (1786643360) [ 1973.665566] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1979.678711] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1998.263854] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1999.838664] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 2003.216767] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 2006.573126] Lustre: Unmounted lustre-client [ 2017.420268] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 2061.358899] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2065.868342] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2066.629110] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 2071.167582] Lustre: Unmounted lustre-client [ 2135.329412] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2141.512736] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2142.053578] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 2146.688845] Lustre: Unmounted lustre-client [ 2171.269085] Lustre: DEBUG MARKER: files compared the same [ 2195.775256] Key type lgssc unregistered [ 2196.055760] LNet: 50405:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2196.064223] LNetError: 50405:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2196.085432] LNet: Removed LNI 192.168.202.46@tcp [ 2196.923140] Key type .llcrypt unregistered [ 2196.925942] Key type ._llcrypt unregistered [ 2210.355345] Key type ._llcrypt registered [ 2210.356521] Key type .llcrypt registered [ 2211.490854] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 13:53:51 (1786643631) [ 2213.644239] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2213.660354] alg: No test for adler32 (adler32-zlib) [ 2214.674975] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [ 2214.960653] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [ 2216.649181] Key type lgssc registered [ 2217.728465] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2265.511852] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2270.616359] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2289.378418] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2291.864187] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 2295.778798] Lustre: Unmounted lustre-client [ 2323.403792] Key type lgssc unregistered [ 2323.664876] LNet: 53993:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2323.669662] LNetError: 53993:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2324.712908] LNet: Removed LNI 192.168.202.46@tcp [ 2325.413473] Key type .llcrypt unregistered [ 2325.416507] Key type ._llcrypt unregistered [ 2346.399612] Key type ._llcrypt registered [ 2346.401365] Key type .llcrypt registered [ 2347.999977] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 13:56:07 (1786643767) [ 2351.828992] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2351.978948] alg: No test for adler32 (adler32-zlib) [ 2353.214593] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [ 2353.549725] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [ 2355.375223] Key type lgssc registered [ 2357.072898] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2435.799944] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2464.277606] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2469.379750] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2493.991458] Key type lgssc unregistered [ 2494.258434] LNet: 57482:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2494.269947] LNetError: 57482:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2494.281813] LNet: Removed LNI 192.168.202.46@tcp [ 2495.101114] Key type .llcrypt unregistered [ 2495.105153] Key type ._llcrypt unregistered [ 2510.657499] Key type ._llcrypt registered [ 2510.659874] Key type .llcrypt registered [ 2511.981928] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 13:58:51 (1786643931) [ 2514.731514] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2514.886603] alg: No test for adler32 (adler32-zlib) [ 2515.873324] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [ 2516.068987] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [ 2517.719169] Key type lgssc registered [ 2518.575293] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2584.181617] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 2591.256418] Lustre: Unmounted lustre-client [ 2634.561658] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 14:00:54 (1786644054) [ 2746.226695] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2765.870344] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 2772.968233] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2772.974447] LustreError: 63972:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff95fac30b1000: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 2772.992365] Lustre: 58467:0:(llite_lib.c:4362:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.202.146@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 2783.363379] Lustre: Unmounted lustre-client [ 2825.521428] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 14:04:05 (1786644245) [ 2836.867920] Key type lgssc unregistered [ 2837.262897] LNet: 65186:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2837.274084] LNetError: 65186:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2837.316549] LNet: Removed LNI 192.168.202.46@tcp [ 2838.118391] Key type .llcrypt unregistered [ 2838.120620] Key type ._llcrypt unregistered [ 2852.392152] Key type ._llcrypt registered [ 2852.394702] Key type .llcrypt registered [ 2852.699776] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2852.718111] alg: No test for adler32 (adler32-zlib) [ 2853.766824] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [ 2853.959082] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [ 2855.650242] Key type lgssc registered [ 2857.265639] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2907.100578] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2911.861207] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2930.569128] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2933.285355] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 2936.645513] Lustre: Unmounted lustre-client [ 2966.687440] Key type lgssc unregistered [ 2966.977363] LNet: 68207:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2966.985914] LNetError: 68207:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2967.017326] LNet: Removed LNI 192.168.202.46@tcp [ 2967.626164] Key type .llcrypt unregistered [ 2967.628597] Key type ._llcrypt unregistered [ 2977.927768] Key type ._llcrypt registered [ 2977.931828] Key type .llcrypt registered [ 2978.437966] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2978.465534] alg: No test for adler32 (adler32-zlib) [ 2979.552325] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [ 2979.801508] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [ 2981.479936] Key type lgssc registered [ 2982.765288] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3102.451516] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3110.502570] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3135.099976] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3136.667311] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 3139.966338] Lustre: Unmounted lustre-client [ 3175.633841] Key type lgssc unregistered [ 3175.950308] LNet: 71643:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3175.967783] LNetError: 71643:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3176.020636] LNet: Removed LNI 192.168.202.46@tcp [ 3176.892729] Key type .llcrypt unregistered [ 3176.900650] Key type ._llcrypt unregistered [ 3192.284300] Key type ._llcrypt registered [ 3192.287197] Key type .llcrypt registered [ 3193.287699] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 14:10:13 (1786644613) [ 3196.011646] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3196.180760] alg: No test for adler32 (adler32-zlib) [ 3197.403698] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [ 3197.783657] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [ 3199.607185] Key type lgssc registered [ 3201.455164] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3257.017878] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3260.726862] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3280.826233] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3283.197985] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 3287.199107] Lustre: Unmounted lustre-client [ 3287.686461] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 3290.479489] Lustre: Unmounted lustre-client [ 3318.479768] Key type lgssc unregistered [ 3318.702642] LNet: 75337:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3318.707035] LNetError: 75337:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3319.719476] LNet: Removed LNI 192.168.202.46@tcp [ 3320.649049] Key type .llcrypt unregistered [ 3320.650898] Key type ._llcrypt unregistered [ 3337.901560] Key type ._llcrypt registered [ 3337.904241] Key type .llcrypt registered [ 3338.490793] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3338.532395] alg: No test for adler32 (adler32-zlib) [ 3339.768468] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [ 3340.061106] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [ 3341.807774] Key type lgssc registered [ 3342.929854] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3398.610425] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3406.600073] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3429.633776] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3442.229039] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 3446.443020] Lustre: Unmounted lustre-client [ 3484.273192] Key type lgssc unregistered [ 3484.662490] LNet: 78397:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3484.672482] LNetError: 78397:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3484.698346] LNet: Removed LNI 192.168.202.46@tcp [ 3485.701637] Key type .llcrypt unregistered [ 3485.703749] Key type ._llcrypt unregistered [ 3501.922698] Key type ._llcrypt registered [ 3501.923915] Key type .llcrypt registered [ 3503.337863] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 14:15:22 (1786644922) [ 3506.567182] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3506.641278] alg: No test for adler32 (adler32-zlib) [ 3507.882035] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [ 3508.128625] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [ 3509.831258] Key type lgssc registered [ 3511.450738] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3568.985663] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3576.019835] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3599.947946] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3601.802367] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 3609.523144] Lustre: lustre: root_squash is set to 0:0 [ 3617.026280] Lustre: lustre: nosquash_nids is cleared [ 3625.526773] Lustre: lustre: root_squash is set to 500:500 [ 3632.094780] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.202.46@tcp 192.168.0.[2,10]@tcp [ 3650.084373] Lustre: lustre: nosquash_nids set to 192.168.202.46/24@tcp [ 3653.162628] Lustre: Unmounted lustre-client [ 3691.002958] Key type lgssc unregistered [ 3691.394847] LNet: 82383:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3691.423311] LNetError: 82383:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3691.472357] LNet: Removed LNI 192.168.202.46@tcp [ 3692.835528] Key type .llcrypt unregistered [ 3692.837108] Key type ._llcrypt unregistered [ 3712.040405] Key type ._llcrypt registered [ 3712.052451] Key type .llcrypt registered [ 3714.058880] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 14:18:52 (1786645132) [ 3717.463328] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3719.290317] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 14:18:58 (1786645138) [ 3721.481069] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3721.552036] alg: No test for adler32 (adler32-zlib) [ 3722.722801] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [ 3723.077339] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [ 3724.887236] Key type lgssc registered [ 3726.585629] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3783.531788] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3789.572812] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3817.623862] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3819.220301] Lustre: lustre: root_squash is set to 500:500 [ 3819.234849] Lustre: lustre: nosquash_nids set to 192.168.202.46/24@tcp [ 3820.389613] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 3826.734136] Lustre: Unmounted lustre-client [ 3854.729975] Key type lgssc unregistered [ 3855.104336] LNet: 86349:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3855.112728] LNetError: 86349:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3855.139446] LNet: Removed LNI 192.168.202.46@tcp [ 3856.196337] Key type .llcrypt unregistered [ 3856.198421] Key type ._llcrypt unregistered [ 3876.781682] Key type ._llcrypt registered [ 3876.786338] Key type .llcrypt registered [ 3878.458782] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 3880.471864] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 14:21:39 (1786645299) [ 3903.194286] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3903.203600] alg: No test for adler32 (adler32-zlib) [ 3904.270593] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [ 3904.611880] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [ 3906.304503] Key type lgssc registered [ 3907.516830] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4003.816089] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4009.188660] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4033.162229] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4102.552071] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4108.835773] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4129.309751] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4141.823590] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 4176.133712] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff95fad8009800.ost_server_uuid 50 [ 4177.476445] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff95fad8009800.ost_server_uuid in FULL state after 0 sec [ 4177.950627] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 4181.083873] Lustre: Unmounted lustre-client [ 4182.985587] Lustre: Unmounted lustre-client [ 4222.733110] Key type lgssc unregistered [ 4223.007817] LNet: 94023:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4223.026875] LNetError: 94023:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4223.046061] LNet: Removed LNI 192.168.202.46@tcp [ 4223.766938] Key type .llcrypt unregistered [ 4223.768736] Key type ._llcrypt unregistered [ 4256.790605] Key type ._llcrypt registered [ 4256.796313] Key type .llcrypt registered [ 4258.969419] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 14:27:57 (1786645677) [ 4284.513403] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4284.569231] alg: No test for adler32 (adler32-zlib) [ 4285.630756] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [ 4285.844390] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [ 4287.660463] Key type lgssc registered [ 4290.367550] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4395.341489] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4403.764950] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4429.050827] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4431.908227] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 4453.343463] Lustre: 95390:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786645858/real 1786645858] req@00000000389b2f75 x1873433807820032/t0(0) o400->lustre-OST0000-osc-ffff95fac7a43800@192.168.202.146@tcp:28/4 lens 224/224 e 0 to 1 dl 1786645874 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4453.368765] Lustre: lustre-OST0000-osc-ffff95fac7a43800: Connection to lustre-OST0000 (at 192.168.202.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4458.504144] Lustre: 95390:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786645863/real 1786645863] req@000000004d49cc4d x1873433807820544/t0(0) o400->lustre-OST0000-osc-ffff95fac7a43800@192.168.202.146@tcp:28/4 lens 224/224 e 0 to 1 dl 1786645879 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4463.625160] Lustre: 95390:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786645868/real 1786645868] req@000000003434a28c x1873433807821056/t0(0) o400->lustre-OST0000-osc-ffff95fac7a43800@192.168.202.146@tcp:28/4 lens 224/224 e 0 to 1 dl 1786645884 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4463.721259] Lustre: lustre-OST0000-osc-ffff95fac7a43800: Connection restored to 192.168.202.146@tcp (at 192.168.202.146@tcp) [ 4468.709265] Lustre: lustre-MDT0000-mdc-ffff95fac7a43800: Connection to lustre-MDT0000 (at 192.168.202.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4469.728170] Lustre: 95390:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786645873/real 1786645873] req@000000005135c0d5 x1873433807821568/t0(0) o400->lustre-OST0000-osc-ffff95fac7a43800@192.168.202.146@tcp:28/4 lens 224/224 e 0 to 1 dl 1786645889 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4484.896763] Lustre: 95390:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786645889/real 1786645889] req@0000000018b80a67 x1873433807823872/t0(0) o400->MGC192.168.202.146@tcp@192.168.202.146@tcp:26/25 lens 224/224 e 0 to 1 dl 1786645905 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4484.941650] LustreError: MGC192.168.202.146@tcp: Connection to MGS (at 192.168.202.146@tcp) was lost; in progress operations using this service will fail [ 4495.349851] Lustre: Evicted from MGS (at 192.168.202.146@tcp) after server handle changed from 0x1c88c79e04a98cb2 to 0x1c88c79e04a98e48 [ 4495.381484] Lustre: MGC192.168.202.146@tcp: Connection restored to 192.168.202.146@tcp (at 192.168.202.146@tcp) [ 4503.721325] Lustre: lustre-MDT0000-mdc-ffff95fac7a43800: Connection restored to 192.168.202.146@tcp (at 192.168.202.146@tcp) [ 4509.877238] Lustre: Unmounted lustre-client [ 4537.359195] Key type lgssc unregistered [ 4537.671958] LNet: 99706:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4537.675113] LNetError: 99706:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4537.687759] LNet: Removed LNI 192.168.202.46@tcp [ 4538.304816] Key type .llcrypt unregistered [ 4538.314184] Key type ._llcrypt unregistered [ 4554.517479] Key type ._llcrypt registered [ 4554.519904] Key type .llcrypt registered [ 4556.044588] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 14:32:55 (1786645975) [ 4571.917443] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4571.931897] alg: No test for adler32 (adler32-zlib) [ 4573.177307] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [ 4573.406446] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [ 4575.071205] Key type lgssc registered [ 4577.037779] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4631.907178] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4746.239731] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4752.347354] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4780.478611] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4783.190242] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 4785.432774] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 4787.706455] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 14:36:46 (1786646206) [ 5025.780652] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5036.137278] Lustre: Unmounted lustre-client [ 5169.282276] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5174.586782] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5196.430725] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5233.205721] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 14:44:13 (1786646653) [ 5322.479820] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5329.365590] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5354.217691] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5354.862617] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 5359.117290] Lustre: Unmounted lustre-client [ 5391.291954] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 14:46:50 (1786646810) [ 5479.558875] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5484.218575] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5504.093483] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5504.642445] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 5508.758625] Lustre: Unmounted lustre-client [ 5533.886352] Key type lgssc unregistered [ 5534.115073] LNet: 122736:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5534.124183] LNetError: 122736:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5534.144819] LNet: Removed LNI 192.168.202.46@tcp [ 5534.849454] Key type .llcrypt unregistered [ 5534.851347] Key type ._llcrypt unregistered [ 5549.902798] Key type ._llcrypt registered [ 5549.904447] Key type .llcrypt registered [ 5551.335650] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 14:49:30 (1786646970) [ 5553.639920] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5553.660829] alg: No test for adler32 (adler32-zlib) [ 5554.699409] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [ 5554.916761] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [ 5556.727198] Key type lgssc registered [ 5557.716173] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5598.965194] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5603.477606] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5621.871960] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5627.932830] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 5640.311411] Lustre: Unmounted lustre-client [ 5672.604925] Key type lgssc unregistered [ 5672.761390] LNet: 126330:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5672.769946] LNetError: 126330:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5672.787601] LNet: Removed LNI 192.168.202.46@tcp [ 5673.703852] Key type .llcrypt unregistered [ 5673.706337] Key type ._llcrypt unregistered [ 5690.938560] Key type ._llcrypt registered [ 5690.940693] Key type .llcrypt registered [ 5692.263785] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 14:51:51 (1786647111) [ 5694.660988] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5694.795697] alg: No test for adler32 (adler32-zlib) [ 5695.880895] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [ 5696.177794] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [ 5697.880450] Key type lgssc registered [ 5699.124550] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5747.627342] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5752.758379] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5774.391160] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5777.099285] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 5787.633475] Lustre: lustre-OST0000-osc-ffff95fac6421800: Connection to lustre-OST0000 (at 192.168.202.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5797.840818] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff95fac6421800.ost_server_uuid 50 [ 5799.030726] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 5810.066533] Lustre: Unmounted lustre-client [ 5829.622581] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 14:54:08 (1786647248) [ 5868.298182] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5873.390916] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5900.429800] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5926.315156] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5927.115656] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 5932.536116] Lustre: lustre-OST0000-osc-ffff95fac7a46000: Connection to lustre-OST0000 (at 192.168.202.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5958.934357] Lustre: Unmounted lustre-client [ 6009.428356] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 14:57:08 (1786647428) [ 6047.132184] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6053.610318] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6078.654347] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6100.955573] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6102.714759] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 6108.156734] Lustre: lustre-OST0000-osc-ffff95fac6412800: Connection to lustre-OST0000 (at 192.168.202.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6132.989590] Lustre: Unmounted lustre-client [ 6183.492912] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 15:00:02 (1786647602) [ 6272.833346] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6279.934735] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6306.974535] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6388.200391] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6394.521894] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6419.107357] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6451.262043] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 6498.754882] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6500.878615] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6502.979110] Lustre: Unmounted lustre-client [ 6537.249878] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 15:05:56 (1786647956) [ 6569.855617] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6575.785607] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6596.552469] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6628.144271] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6659.775217] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 6700.426301] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6702.717500] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6706.155480] Lustre: lustre-OST0001-osc-ffff95fac46c3000: Connection to lustre-OST0001 (at 192.168.202.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6715.124726] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6725.514765] Lustre: Unmounted lustre-client [ 6784.639520] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 15:10:04 (1786648204) [ 6819.538326] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6826.035917] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6850.185547] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6850.781763] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 6874.047804] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6875.756871] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6891.538645] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff95fac7c95000.ost_server_uuid 50 [ 6893.838393] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff95fac7c95000.ost_server_uuid in IDLE state after 0 sec [ 6899.075864] Lustre: Unmounted lustre-client [ 6899.587234] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 6899.598751] Lustre: Skipped 3 previous similar messages [ 6899.719727] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 6900.972135] LustreError: 149756:0:(sec.c:403:import_sec_validate_get()) import 00000000381f0562 (NEW) with no sec [ 6971.417788] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 15:13:11 (1786648391) [ 7009.849380] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7016.980830] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7041.046813] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7066.655198] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7066.965634] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 7067.138308] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 7079.828304] Lustre: Unmounted lustre-client [ 7079.833215] Lustre: Skipped 1 previous similar message [ 7116.844364] Key type lgssc unregistered [ 7117.063852] LNet: 153175:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7117.077062] LNetError: 153175:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7118.134841] LNet: Removed LNI 192.168.202.46@tcp [ 7119.485710] Key type .llcrypt unregistered [ 7119.487809] Key type ._llcrypt unregistered [ 7136.174721] Key type ._llcrypt registered [ 7136.177350] Key type .llcrypt registered [ 7138.008312] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 15:15:56 (1786648556) [ 7139.709457] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7139.726571] alg: No test for adler32 (adler32-zlib) [ 7140.834790] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [ 7141.266096] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [ 7142.975216] Key type lgssc registered [ 7144.616808] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7198.299786] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7203.960934] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7231.830351] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7259.224175] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7260.909381] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7262.187462] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 7267.379554] LustreError: lustre-MDT0001-mdc-ffff95fac46c6000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 7267.394602] Lustre: lustre-MDT0001-mdc-ffff95fac46c6000: Connection restored to 192.168.202.146@tcp (at 192.168.202.146@tcp) [ 7276.002275] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7278.465576] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7304.767244] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7310.433979] Lustre: Unmounted lustre-client [ 7352.851662] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 15:19:32 (1786648772) [ 7469.187816] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7475.904559] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7506.785421] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7507.397417] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 7535.614320] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7537.875750] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7550.733412] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7561.145637] Lustre: Unmounted lustre-client [ 7597.123907] Key type lgssc unregistered [ 7597.565821] LNet: 162799:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7597.579618] LNetError: 162799:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7597.624212] LNet: Removed LNI 192.168.202.46@tcp [ 7598.462959] Key type .llcrypt unregistered [ 7598.464530] Key type ._llcrypt unregistered [ 7636.119242] Key type ._llcrypt registered [ 7636.127889] Key type .llcrypt registered [ 7638.072394] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 15:24:16 (1786649056) [ 7641.349737] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7641.471857] alg: No test for adler32 (adler32-zlib) [ 7642.673875] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [ 7643.142711] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [ 7644.888707] Key type lgssc registered [ 7646.426362] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7705.592428] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7714.610989] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7739.382412] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7741.560230] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 7791.069382] Lustre: Unmounted lustre-client [ 7837.234326] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7838.087682] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 7876.946254] Lustre: Unmounted lustre-client [ 7908.313261] Key type lgssc unregistered [ 7908.644355] LNet: 167262:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7908.650437] LNetError: 167262:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7908.679947] LNet: Removed LNI 192.168.202.46@tcp [ 7909.644137] Key type .llcrypt unregistered [ 7909.647218] Key type ._llcrypt unregistered [ 7929.628666] Key type ._llcrypt registered [ 7929.634136] Key type .llcrypt registered [ 7931.375527] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 15:29:10 (1786649350) [ 7934.383141] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7934.476978] alg: No test for adler32 (adler32-zlib) [ 7935.717909] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [ 7935.962361] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [ 7937.632550] Key type lgssc registered [ 7939.307714] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7997.860327] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8006.135567] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8032.693896] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8038.457964] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 8060.375217] Lustre: Unmounted lustre-client [ 8090.988202] Key type lgssc unregistered [ 8091.338121] LNet: 171028:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8091.345630] LNetError: 171028:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8091.365089] LNet: Removed LNI 192.168.202.46@tcp [ 8092.410392] Key type .llcrypt unregistered [ 8092.416966] Key type ._llcrypt unregistered [ 8110.198405] Key type ._llcrypt registered [ 8110.204744] Key type .llcrypt registered [ 8110.851613] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8110.868826] alg: No test for adler32 (adler32-zlib) [ 8112.136866] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [ 8112.452686] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [ 8114.199449] Key type lgssc registered [ 8115.551098] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8173.995580] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8180.090780] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8204.672973] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8207.637496] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 8215.880548] Lustre: Unmounted lustre-client [ 8242.475827] Key type lgssc unregistered [ 8242.902088] LNet: 174101:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8242.910848] LNetError: 174101:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8242.942590] LNet: Removed LNI 192.168.202.46@tcp [ 8243.763140] Key type .llcrypt unregistered [ 8243.766207] Key type ._llcrypt unregistered [ 8261.512072] Key type ._llcrypt registered [ 8261.520243] Key type .llcrypt registered [ 8263.058710] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 15:34:42 (1786649682) [ 8265.688510] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8265.708902] alg: No test for adler32 (adler32-zlib) [ 8266.939619] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [ 8267.557076] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [ 8269.359224] Key type lgssc registered [ 8270.865734] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8336.532886] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8343.294434] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8368.991709] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8374.926042] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 8397.639413] Lustre: Unmounted lustre-client [ 8429.848788] Key type lgssc unregistered [ 8430.166425] LNet: 177865:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8430.183161] LNetError: 177865:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8430.203310] LNet: Removed LNI 192.168.202.46@tcp [ 8431.129125] Key type .llcrypt unregistered [ 8431.135874] Key type ._llcrypt unregistered [ 8448.544372] Key type ._llcrypt registered [ 8448.553404] Key type .llcrypt registered [ 8449.168193] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8449.181438] alg: No test for adler32 (adler32-zlib) [ 8450.435623] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [ 8450.839941] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [ 8452.623231] Key type lgssc registered [ 8454.543593] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8517.564727] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8524.500299] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8549.783908] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8556.090993] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 8564.418952] Lustre: Unmounted lustre-client [ 8594.253275] Key type lgssc unregistered [ 8594.461261] LNet: 180940:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8594.470579] LNetError: 180940:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8594.495756] LNet: Removed LNI 192.168.202.46@tcp [ 8595.250874] Key type .llcrypt unregistered [ 8595.257496] Key type ._llcrypt unregistered [ 8612.519232] Key type ._llcrypt registered [ 8612.522524] Key type .llcrypt registered [ 8614.084925] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 15:40:33 (1786650033) [ 8638.094640] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8638.124066] alg: No test for adler32 (adler32-zlib) [ 8639.420379] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [ 8639.753905] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [ 8641.559763] Key type lgssc registered [ 8643.366084] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8734.932931] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8740.976284] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8763.097283] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8806.919832] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 15:43:46 (1786650226) [ 8846.307851] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8852.040232] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8874.237804] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8877.113073] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 8882.895218] Lustre: Unmounted lustre-client [ 8912.610786] Key type lgssc unregistered [ 8912.943776] LNet: 188046:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8912.954227] LNetError: 188046:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8912.986907] LNet: Removed LNI 192.168.202.46@tcp [ 8913.726080] Key type .llcrypt unregistered [ 8913.727391] Key type ._llcrypt unregistered [ 8933.128362] Key type ._llcrypt registered [ 8933.130937] Key type .llcrypt registered [ 8934.912669] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 8937.301055] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 15:45:56 (1786650356) [ 8959.258437] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8959.308863] alg: No test for adler32 (adler32-zlib) [ 8960.651922] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [ 8961.083652] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [ 8962.913798] Key type lgssc registered [ 8964.716765] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9066.066453] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9071.756991] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9096.649634] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9122.569810] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9124.526569] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 9150.271598] Lustre: Unmounted lustre-client [ 9273.995055] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9280.165331] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9302.918448] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9356.284778] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 15:52:55 (1786650775) [ 9357.870462] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 9359.711993] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 15:52:59 (1786650779) [ 9462.217538] Key type lgssc unregistered [ 9462.510274] LNet: 199499:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9462.522505] LNetError: 199499:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9462.555659] LNet: Removed LNI 192.168.202.46@tcp [ 9463.466316] Key type .llcrypt unregistered [ 9463.469693] Key type ._llcrypt unregistered [ 9480.775615] Key type ._llcrypt registered [ 9480.782421] Key type .llcrypt registered [ 9482.164239] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 15:55:01 (1786650901) [ 9527.602383] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9527.624764] alg: No test for adler32 (adler32-zlib) [ 9528.815649] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [ 9529.163671] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [ 9530.895239] Key type lgssc registered [ 9533.572813] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9585.125282] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9593.268931] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9630.362228] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9661.503244] Key type lgssc unregistered [ 9661.777730] LNet: 203236:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9661.805710] LNetError: 203236:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9661.834398] LNet: Removed LNI 192.168.202.46@tcp [ 9662.594626] Key type .llcrypt unregistered [ 9662.602497] Key type ._llcrypt unregistered [ 9683.844488] Key type ._llcrypt registered [ 9683.846760] Key type .llcrypt registered [ 9685.292273] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 15:58:24 (1786651104) [ 9687.904631] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9688.094464] alg: No test for adler32 (adler32-zlib) [ 9689.354713] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [ 9689.621396] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [ 9691.343379] Key type lgssc registered [ 9692.736292] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9748.545396] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9754.763806] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9780.909815] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9782.726441] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [ 9793.013500] Lustre: lustre-MDT0000-mdc-ffff95fad1720800: Connection to lustre-MDT0000 (at 192.168.202.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9801.200591] Lustre: lustre-MDT0001-mdc-ffff95fad1720800: Connection to lustre-MDT0001 (at 192.168.202.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9812.447516] Lustre: 204228:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786651217/real 1786651217] req@0000000014ac9522 x1873439474401280/t0(0) o400->MGC192.168.202.146@tcp@192.168.202.146@tcp:26/25 lens 224/224 e 0 to 1 dl 1786651233 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9812.489838] LustreError: MGC192.168.202.146@tcp: Connection to MGS (at 192.168.202.146@tcp) was lost; in progress operations using this service will fail [ 9837.043266] Lustre: Evicted from MGS (at 192.168.202.146@tcp) after server handle changed from 0x14cdf6c3a7c68f3a to 0x14cdf6c3a7c6acad [ 9837.057922] Lustre: MGC192.168.202.146@tcp: Connection restored to 192.168.202.146@tcp (at 192.168.202.146@tcp) [ 9846.271268] LustreError: lustre-MDT0000-mdc-ffff95fad1720800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 9846.288220] Lustre: lustre-MDT0000-mdc-ffff95fad1720800: Connection restored to 192.168.202.146@tcp (at 192.168.202.146@tcp) [ 9853.866749] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9855.980428] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9862.217262] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9864.077728] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9868.388260] Lustre: Unmounted lustre-client [ 9903.683397] Key type lgssc unregistered [ 9903.967709] LNet: 208198:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9903.971445] LNetError: 208198:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9903.985943] LNet: Removed LNI 192.168.202.46@tcp [ 9904.656589] Key type .llcrypt unregistered [ 9904.660863] Key type ._llcrypt unregistered [ 9922.617107] Key type ._llcrypt registered [ 9922.618721] Key type .llcrypt registered [ 9924.159163] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 16:02:23 (1786651343) [ 9927.345678] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9927.384277] alg: No test for adler32 (adler32-zlib) [ 9928.583680] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [ 9928.859671] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [ 9930.575219] Key type lgssc registered [ 9932.005962] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9990.546715] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9997.496372] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10062.441697] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10070.763969] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10109.685525] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10137.568655] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10185.990233] Key type lgssc unregistered [10186.207357] LNet: 213500:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10186.214841] LNetError: 213500:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10186.227752] LNet: Removed LNI 192.168.202.46@tcp [10186.904483] Key type .llcrypt unregistered [10186.910969] Key type ._llcrypt unregistered [10233.208949] Key type ._llcrypt registered [10233.211216] Key type .llcrypt registered [10234.682292] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 16:07:34 (1786651654) [10278.681438] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10278.707109] alg: No test for adler32 (adler32-zlib) [10279.734392] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [10280.068456] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [10281.784211] Key type lgssc registered [10283.299586] Lustre: Echo OBD driver; http://www.lustre.org/ [10373.871971] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10380.036793] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10403.591529] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10457.526925] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 16:11:16 (1786651876) [10466.151880] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 16:11:25 (1786651885) [10501.849524] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10508.248626] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10536.019601] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10537.844069] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [10543.828363] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [10545.543618] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [10547.464557] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [10549.287717] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [10553.321840] Lustre: lustre-MDT0000-mdc-ffff95fac7c93000: Connection to lustre-MDT0000 (at 192.168.202.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [10569.695527] Lustre: 215395:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786651974/real 1786651974] req@000000004616a250 x1873440094037632/t0(0) o400->MGC192.168.202.146@tcp@192.168.202.146@tcp:26/25 lens 224/224 e 0 to 1 dl 1786651990 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10569.709515] LustreError: MGC192.168.202.146@tcp: Connection to MGS (at 192.168.202.146@tcp) was lost; in progress operations using this service will fail [10576.577219] Lustre: lustre-MDT0000-mdc-ffff95fac7c93000: Connection restored to 192.168.202.146@tcp (at 192.168.202.146@tcp) [10579.625456] Lustre: Evicted from MGS (at 192.168.202.146@tcp) after server handle changed from 0x1a0cc96bfab0052 to 0x1a0cc96bfab0457 [10579.641210] Lustre: MGC192.168.202.146@tcp: Connection restored to 192.168.202.146@tcp (at 192.168.202.146@tcp) [10585.048320] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10586.538349] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10589.489802] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [10595.310491] Lustre: lustre-MDT0000-mdc-ffff95fac7c93000: Connection to lustre-MDT0000 (at 192.168.202.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [10611.679230] Lustre: 215395:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786652016/real 1786652016] req@000000002f6e9e66 x1873440094045568/t0(0) o400->MGC192.168.202.146@tcp@192.168.202.146@tcp:26/25 lens 224/224 e 0 to 1 dl 1786652032 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10611.753887] LustreError: MGC192.168.202.146@tcp: Connection to MGS (at 192.168.202.146@tcp) was lost; in progress operations using this service will fail [10621.950869] Lustre: Evicted from MGS (at 192.168.202.146@tcp) after server handle changed from 0x1a0cc96bfab0457 to 0x1a0cc96bfab05d1 [10621.995166] Lustre: MGC192.168.202.146@tcp: Connection restored to 192.168.202.146@tcp (at 192.168.202.146@tcp) [10633.089996] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10634.856473] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10639.517791] Lustre: Unmounted lustre-client [10713.564385] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10720.038936] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10743.306638] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10744.065052] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [10751.992663] Lustre: Unmounted lustre-client [10782.717959] Key type lgssc unregistered [10782.975575] LNet: 225980:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10782.985594] LNetError: 225980:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10784.039408] LNet: Removed LNI 192.168.202.46@tcp [10785.604675] Key type .llcrypt unregistered [10785.606483] Key type ._llcrypt unregistered [10805.823480] Key type ._llcrypt registered [10805.825554] Key type .llcrypt registered [10807.011227] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 16:17:06 (1786652226) [10812.579613] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10812.620749] alg: No test for adler32 (adler32-zlib) [10813.843929] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [10814.089975] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [10815.815201] Key type lgssc registered [10817.592632] Lustre: Echo OBD driver; http://www.lustre.org/ [10879.499556] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10887.244808] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10910.919550] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10917.940888] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [10925.872118] Lustre: Unmounted lustre-client [11000.612831] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11009.481364] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11033.339660] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11044.445177] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [11055.190333] Lustre: Unmounted lustre-client [11136.552909] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11142.924940] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11168.580400] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11169.509694] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [11172.407815] Lustre: Unmounted lustre-client [11202.858799] Key type lgssc unregistered [11203.156631] LNet: 233044:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11203.166822] LNetError: 233044:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11203.197865] LNet: Removed LNI 192.168.202.46@tcp [11204.105216] Key type .llcrypt unregistered [11204.109650] Key type ._llcrypt unregistered [11223.818932] Key type ._llcrypt registered [11223.822355] Key type .llcrypt registered [11225.374352] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 16:24:04 (1786652644) [11230.393659] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11230.409228] alg: No test for adler32 (adler32-zlib) [11231.789318] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [11232.212199] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [11234.095950] Key type lgssc registered [11235.591724] Lustre: Echo OBD driver; http://www.lustre.org/ [11292.414992] Key type lgssc unregistered [11292.704537] LNet: 235373:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11292.725519] LNetError: 235373:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11292.769036] LNet: Removed LNI 192.168.202.46@tcp [11293.905405] Key type .llcrypt unregistered [11293.908491] Key type ._llcrypt unregistered [11333.438617] Key type ._llcrypt registered [11333.441986] Key type .llcrypt registered [11333.822537] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11333.848896] alg: No test for adler32 (adler32-zlib) [11335.074682] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [11335.298608] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [11337.007255] Key type lgssc registered [11338.360124] Lustre: Echo OBD driver; http://www.lustre.org/ [11436.186316] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11442.528735] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11470.174358] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11516.309914] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [11517.895453] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 16:28:57 (1786652937) [11555.214934] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11561.973215] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11586.537629] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11616.729395] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11618.679115] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [11623.917559] Lustre: lustre-OST0001-osc-ffff95fac7c93000: Connection to lustre-OST0001 (at 192.168.202.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [11632.138566] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11642.547743] Lustre: Unmounted lustre-client [11689.114957] Key type lgssc unregistered [11689.467772] LNet: 242507:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11689.488233] LNetError: 242507:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11689.525977] LNet: Removed LNI 192.168.202.46@tcp [11690.401582] Key type .llcrypt unregistered [11690.403286] Key type ._llcrypt unregistered [11729.915345] Key type ._llcrypt registered [11729.917624] Key type .llcrypt registered [11731.375792] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 16:32:30 (1786653150) [11745.954429] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11746.071211] alg: No test for adler32 (adler32-zlib) [11747.303074] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [11747.685045] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [11749.519216] Key type lgssc registered [11750.886664] Lustre: Echo OBD driver; http://www.lustre.org/ [11812.654553] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11818.772519] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11843.365571] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 16:34:23 (1786653263) [11881.011874] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11888.338372] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11911.090817] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11913.805482] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [11919.546933] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [11933.183756] Lustre: Unmounted lustre-client [12040.298080] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12046.790304] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12102.168971] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12102.743231] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [12105.414179] Lustre: Unmounted lustre-client [12140.069557] Key type lgssc unregistered [12140.547143] LNet: 250228:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12140.559566] LNetError: 250228:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12140.581950] LNet: Removed LNI 192.168.202.46@tcp [12141.646746] Key type .llcrypt unregistered [12141.658084] Key type ._llcrypt unregistered [12173.577992] Key type ._llcrypt registered [12173.580193] Key type .llcrypt registered [12174.110883] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12174.122101] alg: No test for adler32 (adler32-zlib) [12175.237432] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [12175.565517] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [12177.279445] Key type lgssc registered [12178.367219] Lustre: Echo OBD driver; http://www.lustre.org/ [12285.549168] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12292.360846] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12318.714692] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12364.319419] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [12366.253303] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 16:43:05 (1786653785) [12405.705540] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12412.044522] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12436.592182] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12446.759574] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [12450.530248] Lustre: Unmounted lustre-client [12480.730498] Key type lgssc unregistered [12481.019963] LNet: 256874:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12481.029865] LNetError: 256874:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12481.064790] LNet: Removed LNI 192.168.202.46@tcp [12482.027130] Key type .llcrypt unregistered [12482.032837] Key type ._llcrypt unregistered [12501.621548] Key type ._llcrypt registered [12501.626631] Key type .llcrypt registered [12503.616360] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [12505.675478] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 16:45:24 (1786653924) [12516.156334] Key type .llcrypt unregistered [12516.160848] Key type ._llcrypt unregistered [12526.474336] Key type ._llcrypt registered [12526.488406] Key type .llcrypt registered [12527.125854] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12527.147968] alg: No test for adler32 (adler32-zlib) [12528.329501] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [12528.655079] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [12530.410687] Key type lgssc registered [12531.710829] Lustre: Echo OBD driver; http://www.lustre.org/ [12595.337162] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12620.309667] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [12622.046988] Lustre: Unmounted lustre-client [12651.598044] Key type lgssc unregistered [12651.984690] LNet: 260340:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12652.002986] LNetError: 260340:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12652.056340] LNet: Removed LNI 192.168.202.46@tcp [12652.880321] Key type .llcrypt unregistered [12652.884531] Key type ._llcrypt unregistered [12671.937176] Key type ._llcrypt registered [12671.940413] Key type .llcrypt registered [12673.747929] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 16:48:12 (1786654092) [12676.799532] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12676.844349] alg: No test for adler32 (adler32-zlib) [12678.131301] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [12678.615450] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [12680.383965] Key type lgssc registered [12682.306388] Lustre: Echo OBD driver; http://www.lustre.org/ [12773.280617] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12812.304332] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12819.423179] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12822.342566] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [12826.556951] Lustre: Unmounted lustre-client [12869.926488] Key type lgssc unregistered [12870.279749] LNet: 263892:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12870.296388] LNetError: 263892:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12870.340140] LNet: Removed LNI 192.168.202.46@tcp [12871.210850] Key type .llcrypt unregistered [12871.217672] Key type ._llcrypt unregistered [12889.649185] Key type ._llcrypt registered [12889.651462] Key type .llcrypt registered [12891.036573] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 16:51:50 (1786654310) [12894.171889] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12894.221787] alg: No test for adler32 (adler32-zlib) [12895.339929] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [12895.750925] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [12897.471214] Key type lgssc registered [12899.120094] Lustre: Echo OBD driver; http://www.lustre.org/ [12957.097615] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12964.210353] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12988.648553] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12991.382038] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [12996.584035] Lustre: lustre-MDT0000-mdc-ffff95fac3973800: Connection to lustre-MDT0000 (at 192.168.202.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [12998.684688] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12998.715287] LustreError: 267061:0:(file.c:6156:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [12999.223961] LustreError: 267072:0:(file.c:6156:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [12999.233300] LustreError: 267072:0:(file.c:6156:ll_inode_revalidate_fini()) Skipped 3 previous similar messages [12999.432264] LustreError: MGC192.168.202.146@tcp: Connection to MGS (at 192.168.202.146@tcp) was lost; in progress operations using this service will fail [12999.504661] Lustre: Unmounted lustre-client [13026.210606] Key type lgssc unregistered [13026.777034] LNet: 267480:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13026.792542] LNetError: 267480:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13026.832725] LNet: Removed LNI 192.168.202.46@tcp [13027.894674] Key type .llcrypt unregistered [13027.899111] Key type ._llcrypt unregistered [13047.255459] Key type ._llcrypt registered [13047.259326] Key type .llcrypt registered [13048.919228] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 16:54:28 (1786654468) [13052.183514] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13052.403151] alg: No test for adler32 (adler32-zlib) [13053.793617] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [13054.236254] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [13056.008435] Key type lgssc registered [13057.790642] Lustre: Echo OBD driver; http://www.lustre.org/ [13118.962961] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13125.802526] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13148.875573] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13151.548144] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [13156.869983] Lustre: lustre-MDT0001-mdc-ffff95fad022c000: Connection to lustre-MDT0001 (at 192.168.202.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [13162.656580] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [13165.767355] Lustre: Unmounted lustre-client [13187.589630] Key type lgssc unregistered [13187.844749] LNet: 271068:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13187.850982] LNetError: 271068:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13187.866981] LNet: Removed LNI 192.168.202.46@tcp [13188.707485] Key type .llcrypt unregistered [13188.710615] Key type ._llcrypt unregistered [13206.976420] Key type ._llcrypt registered [13206.978160] Key type .llcrypt registered [13208.352176] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 16:57:07 (1786654627) [13228.645758] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13228.776341] alg: No test for adler32 (adler32-zlib) [13229.963808] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [13230.271849] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [13231.976110] Key type lgssc registered [13233.260749] Lustre: Echo OBD driver; http://www.lustre.org/ [13354.962768] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13357.719728] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [13403.652376] Lustre: lustre-MDT0001-mdc-ffff95fac6413800: Connection to lustre-MDT0001 (at 192.168.202.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [13412.967214] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [13423.324509] Lustre: Unmounted lustre-client [13451.384923] Key type lgssc unregistered [13451.650855] LNet: 276875:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13451.657706] LNetError: 276875:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13451.674340] LNet: Removed LNI 192.168.202.46@tcp [13452.423916] Key type .llcrypt unregistered [13452.430926] Key type ._llcrypt unregistered [13469.704423] Key type ._llcrypt registered [13469.706382] Key type .llcrypt registered [13471.097618] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 17:01:30 (1786654890) [13473.035569] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [13474.931715] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 17:01:34 (1786654894) [13476.773298] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [13478.664515] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 17:01:38 (1786654898) [13480.103671] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [13481.788749] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 17:01:41 (1786654901) [13483.577745] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [13485.488478] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 17:01:45 (1786654905) [13486.878425] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [13488.767579] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 17:01:48 (1786654908) [13498.838516] Key type .llcrypt unregistered [13498.842135] Key type ._llcrypt unregistered [13507.438704] Key type ._llcrypt registered [13507.440746] Key type .llcrypt registered [13507.805136] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13507.821171] alg: No test for adler32 (adler32-zlib) [13509.065654] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [13509.300937] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [13511.175198] Key type lgssc registered [13512.542229] Lustre: Echo OBD driver; http://www.lustre.org/ [13584.495845] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13589.581846] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13612.943231] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13614.492840] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [13617.279239] Lustre: Unmounted lustre-client [13649.803342] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 17:04:29 (1786655069) [13685.578787] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13692.170442] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13727.179712] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13733.494114] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [13736.899648] Lustre: Unmounted lustre-client [13784.493952] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 17:06:43 (1786655203) [13785.823357] LNet: 1 local NIs in recovery (showing 1): 192.168.202.46@tcp [14441.101497] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14447.290600] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14483.748446] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14588.702827] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 17:20:08 (1786656008) [14602.564492] Key type lgssc unregistered [14602.892730] LNet: 290424:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14602.911631] LNetError: 290424:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14603.943299] LNet: Removed LNI 192.168.202.46@tcp [14604.750719] Key type .llcrypt unregistered [14604.755468] Key type ._llcrypt unregistered [14731.634466] Key type ._llcrypt registered [14731.646359] Key type .llcrypt registered [14732.352192] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14732.369203] alg: No test for adler32 (adler32-zlib) [14733.624457] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [14734.006237] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [14735.743263] Key type lgssc registered [14737.326914] Lustre: Echo OBD driver; http://www.lustre.org/ [14834.290324] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14841.133965] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14868.097223] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14905.029717] Key type lgssc unregistered [14905.197031] LNet: 294140:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14905.216762] LNetError: 294140:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14905.241215] LNet: Removed LNI 192.168.202.46@tcp [14906.035791] Key type .llcrypt unregistered [14906.043844] Key type ._llcrypt unregistered [14949.471933] Key type ._llcrypt registered [14949.477812] Key type .llcrypt registered [14951.012968] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 17:26:10 (1786656370) [14975.436658] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14975.459820] alg: No test for adler32 (adler32-zlib) [14976.612425] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [14976.977043] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [14978.713071] Key type lgssc registered [14980.259322] Lustre: Echo OBD driver; http://www.lustre.org/ [15033.252209] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 17:27:32 (1786656452) [15045.165981] Key type lgssc unregistered [15045.531315] LNet: 297702:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15045.540642] LNetError: 297702:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15045.563432] LNet: Removed LNI 192.168.202.46@tcp [15046.484485] Key type .llcrypt unregistered [15046.486796] Key type ._llcrypt unregistered [15058.691792] Key type ._llcrypt registered [15058.696909] Key type .llcrypt registered [15059.066603] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15059.080152] alg: No test for adler32 (adler32-zlib) [15060.278438] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [15060.499046] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [15062.159489] Key type lgssc registered [15063.725880] Lustre: Echo OBD driver; http://www.lustre.org/ [15123.643716] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15129.005458] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15155.788918] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15494.418992] LNet: Added LNI 192.168.202.46@tcp50 [8/256/0/180] [15497.293330] LNet: Removed LNI 192.168.202.46@tcp [15499.530947] Lustre: 302130:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff95fae0a81800: config has no valid NIDs, force dynamic NIDs [15500.811925] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [15501.078743] Lustre: Unmounted lustre-client [15503.871906] Key type lgssc unregistered [15504.286820] LNet: 302392:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15504.290780] LNetError: 302392:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15504.321058] LNet: Removed LNI 192.168.202.46@tcp50 [15504.971307] Key type .llcrypt unregistered [15504.976107] Key type ._llcrypt unregistered [15524.306204] Key type ._llcrypt registered [15524.309901] Key type .llcrypt registered [15524.917093] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15524.953654] alg: No test for adler32 (adler32-zlib) [15526.033664] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [15526.271450] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [15527.935896] Key type lgssc registered [15529.365420] Lustre: Echo OBD driver; http://www.lustre.org/ [15590.236305] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15596.116667] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15604.235656] LNet: Added LNI 192.168.202.46@tcp50 [8/256/0/180] [15605.245444] LNet: Removed LNI 192.168.202.46@tcp [15606.446578] Lustre: 305086:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff95fac46c2000: config has no valid NIDs, force dynamic NIDs [15607.636574] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [15608.446663] Lustre: Unmounted lustre-client [15653.318765] Key type lgssc unregistered [15653.784671] LNet: 305965:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15653.795661] LNetError: 305965:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15654.831065] LNet: Removed LNI 192.168.202.46@tcp50 [15655.493504] Key type .llcrypt unregistered [15655.496644] Key type ._llcrypt unregistered [15668.323247] Key type ._llcrypt registered [15668.329565] Key type .llcrypt registered [15669.573251] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 17:38:09 (1786657089) [15688.511826] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15688.582700] alg: No test for adler32 (adler32-zlib) [15689.663364] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [15690.002124] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [15691.696187] Key type lgssc registered [15693.079993] Lustre: Echo OBD driver; http://www.lustre.org/ [15781.639599] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15787.876744] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15814.395879] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15854.319475] Key type lgssc unregistered [15854.559834] LNet: 310719:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15854.566285] LNetError: 310719:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15854.577507] LNet: Removed LNI 192.168.202.46@tcp [15855.215852] Key type .llcrypt unregistered [15855.217510] Key type ._llcrypt unregistered [15867.609290] Key type ._llcrypt registered [15867.611353] Key type .llcrypt registered [15869.130831] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 17:41:28 (1786657288) [15871.737301] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15871.790563] alg: No test for adler32 (adler32-zlib) [15873.006335] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [15873.272694] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [15874.975228] Key type lgssc registered [15876.486339] Lustre: Echo OBD driver; http://www.lustre.org/ [15927.386157] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15933.511725] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15958.417194] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15961.144942] Lustre: Mounted lustre-client - version 2.17.57_1_g5840bc4 [16023.331979] Lustre: Mounted fs15246-client - version 2.17.57_1_g5840bc4 [16039.021894] Lustre: Unmounted fs15246-client [16070.238362] Lustre: Unmounted lustre-client [16099.486527] Key type lgssc unregistered [16099.727118] LNet: 315586:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16099.735639] LNetError: 315586:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16099.762623] LNet: Removed LNI 192.168.202.46@tcp [16100.383666] Key type .llcrypt unregistered [16100.385866] Key type ._llcrypt unregistered [16112.713302] Key type ._llcrypt registered [16112.715514] Key type .llcrypt registered [16114.098424] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 17:45:33 (1786657533) [16115.816763] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16115.856357] alg: No test for adler32 (adler32-zlib) [16117.037340] Lustre: Lustre: Build Version: 2.17.57_1_g5840bc4 [16117.360541] LNet: Added LNI 192.168.202.46@tcp [8/256/0/180] [16119.079538] Key type lgssc registered [16120.564434] Lustre: Echo OBD driver; http://www.lustre.org/ [16262.285243] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16267.640172] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16292.448736] Lustre: DEBUG MARKER: oleg246-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16396.823826] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 16093 sec ========= 17:50:15 (1786657815) [16398.772594] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 17:50:18 (1786657818) === [16402.595912] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 17:50:22 (1786657822) === [16441.983260] Key type lgssc unregistered [16442.264465] LNet: 324862:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16442.271775] LNetError: 324862:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16442.286967] LNet: Removed LNI 192.168.202.46@tcp [16442.995388] Key type .llcrypt unregistered [16442.999125] Key type ._llcrypt unregistered