[ 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 542009589 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 2496MB 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.002226] x2apic enabled [ 0.003006] Switched APIC routing to physical x2apic. [ 0.004012] kvm-guest: setup PV IPIs [ 0.006522] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.007000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.007021] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.008006] pid_max: default: 32768 minimum: 301 [ 0.009000] LSM: Security Framework initializing [ 0.009049] Yama: becoming mindful. [ 0.010046] SELinux: Initializing. [ 0.011055] *** VALIDATE selinux *** [ 0.020026] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.024528] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.025135] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.027011] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028095] *** VALIDATE tmpfs *** [ 0.030120] *** VALIDATE proc *** [ 0.031170] *** VALIDATE cgroup *** [ 0.031808] *** VALIDATE cgroup2 *** [ 0.032252] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.033108] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.034003] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.035021] Spectre V2 : User space: Vulnerable [ 0.036003] Speculative Store Bypass: Vulnerable [ 0.038541] debug: unmapping init [mem 0xffffffffb7a59000-0xffffffffb7a60fff] [ 0.040257] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.041699] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.042020] ... version: 2 [ 0.043010] ... bit width: 48 [ 0.044010] ... generic registers: 4 [ 0.045008] ... value mask: 0000ffffffffffff [ 0.046009] ... max period: 00007fffffffffff [ 0.047010] ... fixed-purpose events: 3 [ 0.048008] ... event mask: 000000070000000f [ 0.049270] rcu: Hierarchical SRCU implementation. [ 0.051598] smp: Bringing up secondary CPUs ... [ 0.052526] x86: Booting SMP configuration: [ 0.053012] .... node #0, CPUs: #1 #2 #3 [ 0.056413] smp: Brought up 1 node, 4 CPUs [ 0.058012] smpboot: Max logical packages: 1 [ 0.059010] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.108945] node 0 deferred pages initialised in 47ms [ 0.112194] devtmpfs: initialized [ 0.113309] x86/mm: Memory block size: 128MB [ 0.115886] gcov: version magic: 0x41383552 [ 0.117211] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.118059] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.119225] pinctrl core: initialized pinctrl subsystem [ 0.120113] [ 0.120491] ************************************************************* [ 0.121007] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.122011] ** ** [ 0.123010] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.124008] ** ** [ 0.125011] ** This means that this kernel is built to expose internal ** [ 0.126008] ** IOMMU data structures, which may compromise security on ** [ 0.127006] ** your system. ** [ 0.128005] ** ** [ 0.129006] ** If you see this message and you are not debugging the ** [ 0.130216] ** kernel, report this immediately to your vendor! ** [ 0.131009] ** ** [ 0.132007] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.133006] ************************************************************* [ 0.134469] NET: Registered protocol family 16 [ 0.135371] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.136048] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.137043] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.138488] cpuidle: using governor menu [ 0.141906] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.143563] PCI: Using configuration type 1 for base access [ 0.145151] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.157105] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.158012] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.160058] cryptd: max_cpu_qlen set to 1000 [ 0.164378] ACPI: Added _OSI(Module Device) [ 0.165019] ACPI: Added _OSI(Processor Device) [ 0.167011] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.168010] ACPI: Added _OSI(Processor Aggregator Device) [ 0.173267] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.179629] ACPI: Interpreter enabled [ 0.180076] ACPI: PM: (supports S0 S3 S4 S5) [ 0.182012] ACPI: Using IOAPIC for interrupt routing [ 0.183080] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.186371] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.198414] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.201036] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.207037] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.212102] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.218164] acpiphp: Slot [2] registered [ 0.224156] acpiphp: Slot [5] registered [ 0.227404] acpiphp: Slot [6] registered [ 0.229186] acpiphp: Slot [3] registered [ 0.230000] acpiphp: Slot [4] registered [ 0.230000] acpiphp: Slot [7] registered [ 0.232080] acpiphp: Slot [8] registered [ 0.233060] acpiphp: Slot [9] registered [ 0.234259] acpiphp: Slot [10] registered [ 0.235000] acpiphp: Slot [11] registered [ 0.235000] acpiphp: Slot [12] registered [ 0.237353] acpiphp: Slot [13] registered [ 0.238275] acpiphp: Slot [14] registered [ 0.240154] acpiphp: Slot [15] registered [ 0.242138] acpiphp: Slot [16] registered [ 0.244141] acpiphp: Slot [17] registered [ 0.245132] acpiphp: Slot [18] registered [ 0.256192] acpiphp: Slot [19] registered [ 0.259260] acpiphp: Slot [20] registered [ 0.261173] acpiphp: Slot [21] registered [ 0.267196] acpiphp: Slot [22] registered [ 0.272177] acpiphp: Slot [23] registered [ 0.280244] acpiphp: Slot [24] registered [ 0.283199] acpiphp: Slot [25] registered [ 0.284187] acpiphp: Slot [26] registered [ 0.285137] acpiphp: Slot [27] registered [ 0.288194] acpiphp: Slot [28] registered [ 0.290168] acpiphp: Slot [29] registered [ 0.292146] acpiphp: Slot [30] registered [ 0.302252] acpiphp: Slot [31] registered [ 0.305170] PCI host bridge to bus 0000:00 [ 0.308207] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.311046] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.313032] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.317044] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.320097] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.322034] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.323220] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.326240] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.347228] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.428023] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.471997] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.569078] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.594026] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.596025] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.599538] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.602215] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.604053] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.608109] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.614024] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.642026] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.711032] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.734963] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.741027] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.748023] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.764027] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.782000] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.782000] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.782000] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.782000] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.782000] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.782000] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.782000] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.782000] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.782000] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.782000] iommu: Default domain type: Passthrough [ 0.782000] SCSI subsystem initialized [ 0.782000] ACPI: bus type USB registered [ 0.782000] usbcore: registered new interface driver usbfs [ 0.782000] usbcore: registered new interface driver hub [ 0.782000] usbcore: registered new device driver usb [ 0.782000] pps_core: LinuxPPS API ver. 1 registered [ 0.790032] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.793113] PTP clock support registered [ 0.795079] EDAC MC: Ver: 3.0.0 [ 0.796054] PCI: Using ACPI for IRQ routing [ 0.799108] NetLabel: Initializing [ 0.800015] NetLabel: domain hash size = 128 [ 0.802014] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.804308] NetLabel: unlabeled traffic allowed by default [ 0.807938] vgaarb: loaded [ 0.810360] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.812258] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.819624] clocksource: Switched to clocksource kvm-clock [ 1.038685] VFS: Disk quotas dquot_6.6.0 [ 1.042457] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 1.049542] *** VALIDATE ramfs *** [ 1.053260] *** VALIDATE hugetlbfs *** [ 1.059457] pnp: PnP ACPI init [ 1.066810] pnp: PnP ACPI: found 6 devices [ 1.119625] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 1.122488] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 1.124185] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 1.125777] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 1.127738] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 1.130603] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 1.135679] NET: Registered protocol family 2 [ 1.141376] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 1.149772] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 1.155358] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 1.162538] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 1.167322] TCP: Hash tables configured (established 65536 bind 65536) [ 1.171642] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 1.176534] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 1.180895] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 1.185792] NET: Registered protocol family 1 [ 1.190698] RPC: Registered named UNIX socket transport module. [ 1.194237] RPC: Registered udp transport module. [ 1.196569] RPC: Registered tcp transport module. [ 1.326865] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 1.329948] NET: Registered protocol family 44 [ 1.332188] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 1.335127] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 1.338380] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 1.341647] PCI: CLS 0 bytes, default 64 [ 1.343914] Unpacking initramfs... [ 3.854314] debug: unmapping init [mem 0xffff91247cc64000-0xffff91247ffcffff] [ 3.860624] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 3.862456] software IO TLB: mapped [mem 0x00000000b8c64000-0x00000000bcc64000] (64MB) [ 3.866291] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 5.010071] Initialise system trusted keyrings [ 5.014207] Key type blacklist registered [ 5.018321] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 5.040210] zbud: loaded [ 5.046615] *** VALIDATE nfs *** [ 5.049566] *** VALIDATE nfs4 *** [ 5.056644] pstore: using deflate compression [ 5.071859] Platform Keyring initialized [ 5.325241] NET: Registered protocol family 38 [ 5.331076] Key type asymmetric registered [ 5.334429] Asymmetric key parser 'x509' registered [ 5.339583] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 5.348197] io scheduler mq-deadline registered [ 5.351640] io scheduler kyber registered [ 5.354634] io scheduler bfq registered [ 5.362569] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 5.370843] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 5.378917] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 5.389713] ACPI: Power Button [PWRF] [ 5.400724] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 5.409326] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 5.420495] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 5.460610] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 5.504889] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 5.515074] Non-volatile memory driver v1.3 [ 5.517741] Linux agpgart interface v0.103 [ 5.644945] virtio_blk virtio1: [vda] 149976 512-byte logical blocks (76.8 MB/73.2 MiB) [ 5.652523] vda: detected capacity change from 0 to 76787712 [ 5.697419] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 5.703276] vdb: detected capacity change from 0 to 1073741824 [ 5.719798] libphy: Fixed MDIO Bus: probed [ 5.729990] usbcore: registered new interface driver usbserial_generic [ 5.732676] usbserial: USB Serial support registered for generic [ 5.736392] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 5.741883] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 5.743925] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 5.746913] mousedev: PS/2 mouse device common for all mice [ 5.750025] rtc_cmos 00:05: RTC can wake from S4 [ 5.757173] rtc_cmos 00:05: registered as rtc0 [ 5.760438] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 5.766568] intel_pstate: CPU model not supported [ 5.780438] hid: raw HID events driver (C) Jiri Kosina [ 5.781212] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 5.787122] usbcore: registered new interface driver usbhid [ 5.787129] usbhid: USB HID core driver [ 5.787248] drop_monitor: Initializing network drop monitor service [ 5.787416] Initializing XFRM netlink socket [ 5.789095] NET: Registered protocol family 10 [ 5.803530] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 5.810464] Segment Routing with IPv6 [ 5.829644] NET: Registered protocol family 17 [ 5.835862] mpls_gso: MPLS GSO support [ 5.837165] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 5.862417] RAS: Correctable Errors collector initialized. [ 5.868228] AVX version of gcm_enc/dec engaged. [ 5.873271] AES CTR mode by8 optimization enabled [ 6.039510] sched_clock: Marking stable (6039419762, 0)->(7111545157, -1072125395) [ 6.043525] registered taskstats version 1 [ 6.045996] Loading compiled-in X.509 certificates [ 6.048668] zswap: loaded using pool lzo/zbud [ 6.077785] Key type big_key registered [ 6.090820] Key type encrypted registered [ 6.092428] ima: No TPM chip found, activating TPM-bypass! [ 6.094316] ima: Allocated hash algorithm: sha1 [ 6.095990] ima: No architecture policies found [ 6.097829] evm: Initialising EVM extended attributes: [ 6.099632] evm: security.selinux [ 6.101189] evm: security.ima [ 6.102386] evm: security.capability [ 6.103777] evm: HMAC attrs: 0x1 [ 6.106338] rtc_cmos 00:05: setting system clock to 2026-09-07 20:27:28 UTC (1788812848) [ 6.123484] debug: unmapping init [mem 0xffffffffb8a03000-0xffffffffb8bfffff] [ 6.126838] debug: unmapping init [mem 0xffffffffb7782000-0xffffffffb7a58fff] [ 6.140240] Write protecting the kernel read-only data: 28672k [ 6.162531] debug: unmapping init [mem 0xffffffffb5e03000-0xffffffffb5ffffff] [ 6.168526] debug: unmapping init [mem 0xffffffffb6714000-0xffffffffb67fffff] [ 6.220454] 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) [ 6.234839] systemd[1]: Detected virtualization kvm. [ 6.237463] systemd[1]: Detected architecture x86-64. [ 6.245165] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 6.313893] systemd[1]: No hostname configured. [ 6.325417] systemd[1]: Set hostname to . [ 6.335610] random: systemd: uninitialized urandom read (16 bytes read) [ 6.345628] systemd[1]: Initializing machine ID from random generator. [ 6.522623] random: ln: uninitialized urandom read (6 bytes read) [ 6.754171] random: systemd: uninitialized urandom read (16 bytes read) [ 6.761351] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 6.784705] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 6.800526] systemd[1]: Reached target Paths. [ OK ] Reached target Paths. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Timers. [ OK ] Reached target Swap. [ OK ] Reached target Slices. [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Local File Systems. [ OK ] Listening on Journal Socket. Starting Setup Virtual Console... Starting Journal Service... [ OK ] Reached target Sockets. [ OK ] Started Memstrack Anylazing Service. Starting Create Volatile Files and Directories... Starting Apply Kernel Variables... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Setup Virtual Console. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. [ 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... [ 8.500646] device-mapper: uevent: version 1.0.3 [ 8.502986] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 11.761564] virtio_net virtio0 ens2: renamed from eth0 [ 12.111858] scsi host0: ata_piix [ 12.427285] scsi host1: ata_piix [ 12.450202] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 12.486767] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 15.018212] random: fast init done [ 17.643751] random: crng init done [ 17.651563] random: 7 urandom warning(s) missed due to ratelimiting [ 19.403878] dracut-initqueue[592]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 20.592477] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Remote File Systems. [ 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 Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped target Swap. Stopping udev Kernel Device Manager... [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 23.351869] printk: systemd: 22 output lines suppressed due to ratelimiting [ 24.616339] SELinux: Disabled at runtime. [ 24.713520] 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) [ 24.727165] systemd[1]: Detected virtualization kvm. [ 24.731440] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 26.903487] systemd[1]: initrd-switch-root.service: Succeeded. [ 26.908588] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 26.918707] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 26.929196] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 26.931945] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 26.950171] systemd[1]: Starting Journal Service... Starting Journal Service... [ 26.975710] systemd[1]: Mounting POSIX Message Queue File System... Mounting POSIX Message Queue File System... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on RPCbind Server Activation Socket. Starting Remount Root and Kernel File Systems... [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Created slice system-serial\x2dgetty.slice. Mounting Kernel Debug File System... [ OK ] Reached target RPC Port Mapper. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Paths. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Created slice system-getty.slice. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. Starting Apply Kernel Variables... Activating swap /dev/disk/by-label/SWAP... [ OK ] Reached target Local Encrypted Volumes. Mounting Huge Pages File System... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Sy[ 27.421995] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS stems. [ OK ] Stopped target Initrd Root File System. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on Process Core Dump Socket. [ OK ] Reached target rpc_pipefs.target. [ OK ] Started Journal Service. [ OK ] Mounted POSIX Message Queue File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Huge Pages File System. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started udev Coldplug all Devices. [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 28.196720] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 29.098616] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 29.129266] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 29.791947] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 29.866832] EDAC sbridge: Ver: 1.1.2 [ 33.167136] Key type dns_resolver registered [ 33.629699] NFS: Registering the id_resolver key type [ 33.631936] Key type id_resolver registered [ 33.633852] Key type id_legacy registered [* ] A start job is running for Configur…-only root support (7s / no limit) [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started dnf makecache --timer. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Restore /run/initramfs on shutdown... [ OK ] Started D-Bus System Message Bus. Starting Login Service... [ OK ] Started irqbalance daemon. [ OK ] Reached target sshd-keygen.target. Starting Network Manager... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... Starting Network Manager Wait Online... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. 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 Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting System Logging Service... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg238-client login: [ 80.584533] libcfs: loading out-of-tree module taints kernel. [ 80.671084] Key type ._llcrypt registered [ 80.678034] Key type .llcrypt registered [ 81.141757] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 81.149837] alg: No test for adler32 (adler32-zlib) [ 82.688417] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [ 83.229342] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [ 84.927633] Key type lgssc registered [ 86.130917] Lustre: Echo OBD driver; http://www.lustre.org/ [ 179.651081] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 183.007892] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 189.914894] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing check_logdir /tmp/testlogs/ [ 192.334727] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing yml_node [ 194.695996] Lustre: DEBUG MARKER: Client: 2.17.58.41 [ 195.903370] Lustre: DEBUG MARKER: MDS: 2.17.58.41 [ 197.188364] Lustre: DEBUG MARKER: OSS: 2.17.58.41 [ 198.041982] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Mon Sep 7 16:30:39 EDT 2026 [ 205.280119] Lustre: lustre-OST0000-osc-ffff9124d1b7d800: disconnect after 24s idle [ 206.791811] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 207.780778] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 209.484748] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 221.080503] Lustre: Unmounted lustre-client [ 270.449374] hrtimer: interrupt took 6161888 ns [ 363.774812] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 369.682499] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 394.189318] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 429.963858] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 16:34:31 (1788813271) [ 432.342090] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 433.852910] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 16:34:35 (1788813275) [ 465.521887] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 471.660377] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 492.442822] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 493.259897] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 498.553051] Lustre: Unmounted lustre-client [ 526.756598] Key type lgssc unregistered [ 526.971298] LNet: 15026:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 526.985349] LNetError: 15026:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 527.012668] LNet: Removed LNI 192.168.202.38@tcp [ 527.875227] Key type .llcrypt unregistered [ 527.877468] Key type ._llcrypt unregistered [ 543.994138] Key type ._llcrypt registered [ 544.000273] Key type .llcrypt registered [ 545.773945] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 16:36:26 (1788813386) [ 547.543302] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 547.761125] alg: No test for adler32 (adler32-zlib) [ 548.918967] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [ 549.125818] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [ 550.783311] Key type lgssc registered [ 551.958348] Lustre: Echo OBD driver; http://www.lustre.org/ [ 636.256764] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 728.842222] Lustre: Unmounted lustre-client [ 759.839140] Key type lgssc unregistered [ 760.094774] LNet: 18839:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 760.105491] LNetError: 18839:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 760.120501] LNet: Removed LNI 192.168.202.38@tcp [ 760.760229] Key type .llcrypt unregistered [ 760.761798] Key type ._llcrypt unregistered [ 772.768084] Key type ._llcrypt registered [ 772.781903] Key type .llcrypt registered [ 774.262971] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 16:40:14 (1788813614) [ 776.774179] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 776.805902] alg: No test for adler32 (adler32-zlib) [ 777.957491] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [ 778.257417] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [ 779.945157] Key type lgssc registered [ 781.373628] Lustre: Echo OBD driver; http://www.lustre.org/ [ 836.566943] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 843.106274] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 863.832447] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 865.533522] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 880.226511] Lustre: Unmounted lustre-client [ 910.371985] Key type lgssc unregistered [ 910.641319] LNet: 22443:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 910.652915] LNetError: 22443:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 910.677957] LNet: Removed LNI 192.168.202.38@tcp [ 911.546345] Key type .llcrypt unregistered [ 911.548320] Key type ._llcrypt unregistered [ 922.603807] Key type ._llcrypt registered [ 922.606107] Key type .llcrypt registered [ 924.313793] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 16:42:44 (1788813764) [ 926.724167] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 926.870345] alg: No test for adler32 (adler32-zlib) [ 927.985593] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [ 928.261707] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [ 930.047398] Key type lgssc registered [ 931.577217] Lustre: Echo OBD driver; http://www.lustre.org/ [ 983.678401] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 989.757336] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1011.421782] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1013.281192] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 1021.814520] Lustre: Unmounted lustre-client [ 1049.284334] Key type lgssc unregistered [ 1049.592566] LNet: 25632:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1049.599162] LNetError: 25632:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1049.618609] LNet: Removed LNI 192.168.202.38@tcp [ 1050.397651] Key type .llcrypt unregistered [ 1050.400408] Key type ._llcrypt unregistered [ 1068.653532] Key type ._llcrypt registered [ 1068.656836] Key type .llcrypt registered [ 1070.333421] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 16:45:10 (1788813910) [ 1073.184713] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1073.330043] alg: No test for adler32 (adler32-zlib) [ 1074.410302] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [ 1074.617467] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [ 1076.295523] Key type lgssc registered [ 1077.764671] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1123.624252] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1128.383985] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1148.764655] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1150.457085] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 1155.570689] Lustre: lustre-MDT0000-mdc-ffff9124c9d66000: Connection to lustre-MDT0000 (at 192.168.202.138@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1165.804874] Lustre: lustre-MDT0001-mdc-ffff9124c9d66000: Connection to lustre-MDT0001 (at 192.168.202.138@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1172.522811] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1181.983256] Lustre: 26588:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788814008/real 1788814008] req@000000000ea6362e x1875707341838208/t0(0) o400->MGC192.168.202.138@tcp@192.168.202.138@tcp:26/25 lens 224/224 e 0 to 1 dl 1788814024 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1182.010467] LustreError: MGC192.168.202.138@tcp: Connection to MGS (at 192.168.202.138@tcp) was lost; in progress operations using this service will fail [ 1182.906968] Lustre: Unmounted lustre-client [ 1200.152308] Key type lgssc unregistered [ 1200.363785] LNet: 29281:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1200.376367] LNetError: 29281:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1200.403525] LNet: Removed LNI 192.168.202.38@tcp [ 1201.131318] Key type .llcrypt unregistered [ 1201.134428] Key type ._llcrypt unregistered [ 1215.342692] Key type ._llcrypt registered [ 1215.344908] Key type .llcrypt registered [ 1216.541388] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 16:47:37 (1788814057) [ 1219.487202] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1219.536335] alg: No test for adler32 (adler32-zlib) [ 1220.683132] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [ 1220.926623] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [ 1222.578378] Key type lgssc registered [ 1223.763529] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1270.975946] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1275.944545] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1296.751903] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1299.558115] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 1304.568694] Lustre: lustre-OST0000-osc-ffff9124c5eb3000: Connection to lustre-OST0000 (at 192.168.202.138@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1320.221057] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1330.556513] Lustre: Unmounted lustre-client [ 1357.229637] Key type lgssc unregistered [ 1357.513914] LNet: 32892:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1357.518787] LNetError: 32892:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1357.545407] LNet: Removed LNI 192.168.202.38@tcp [ 1358.208952] Key type .llcrypt unregistered [ 1358.211887] Key type ._llcrypt unregistered [ 1373.335800] Key type ._llcrypt registered [ 1373.340260] Key type .llcrypt registered [ 1374.919844] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 16:50:15 (1788814215) [ 1377.292463] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1377.323375] alg: No test for adler32 (adler32-zlib) [ 1378.442771] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [ 1378.904979] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [ 1380.695482] Key type lgssc registered [ 1381.739666] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1429.426258] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1435.861716] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1456.371948] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1459.009819] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 1465.856635] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1468.847496] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1480.472395] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-09-0716h52m01s [ 1482.228566] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1484.775923] Lustre: lustre-MDT0000-mdc-ffff9124c2dc5000: Connection to lustre-MDT0000 (at 192.168.202.138@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1492.498451] LustreError: 36679:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff9124c2dc5000: can't stat MDS #0: rc = -19 [ 1494.256900] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1502.189255] LustreError: MGC192.168.202.138@tcp: Connection to MGS (at 192.168.202.138@tcp) was lost; in progress operations using this service will fail [ 1502.207952] Lustre: Evicted from MGS (at 192.168.202.138@tcp) after server handle changed from 0xedc5f30fa0b57c5d to 0xedc5f30fa0b57f0b [ 1502.224865] Lustre: MGC192.168.202.138@tcp: Connection restored to 192.168.202.138@tcp (at 192.168.202.138@tcp) [ 1503.280174] LustreError: lustre-MDT0000-mdc-ffff9124c2dc5000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1503.291660] Lustre: lustre-MDT0000-mdc-ffff9124c2dc5000: Connection restored to 192.168.202.138@tcp (at 192.168.202.138@tcp) [ 1508.803765] Lustre: DEBUG MARKER: Wait for df (36679) ... [ 1510.021548] Lustre: DEBUG MARKER: done [ 1511.820723] Lustre: Unmounted lustre-client [ 1545.629551] Key type lgssc unregistered [ 1545.933368] LNet: 37695:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1545.941828] LNetError: 37695:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1545.959629] LNet: Removed LNI 192.168.202.38@tcp [ 1546.670898] Key type .llcrypt unregistered [ 1546.673479] Key type ._llcrypt unregistered [ 1572.108113] Key type ._llcrypt registered [ 1572.112149] Key type .llcrypt registered [ 1573.359709] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 16:53:34 (1788814414) [ 1575.477359] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1575.563245] alg: No test for adler32 (adler32-zlib) [ 1576.631813] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [ 1576.890915] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [ 1578.575379] Key type lgssc registered [ 1579.924954] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1623.654896] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1628.996783] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1647.963107] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1649.606902] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 1653.192570] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-09-0716h54m54s [ 1654.317715] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1659.898488] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1662.055465] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1682.402883] Lustre: 41831:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788814504/real 1788814504] req@000000006ec036af x1875707868223616/t0(0) o101->lustre-MDT0000-mdc-ffff9124e0c37800@192.168.202.138@tcp:12/10 lens 576/1152 e 0 to 1 dl 1788814524 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1682.422450] Lustre: lustre-MDT0000-mdc-ffff9124e0c37800: Connection to lustre-MDT0000 (at 192.168.202.138@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1682.449667] Lustre: lustre-MDT0000-mdc-ffff9124e0c37800: Connection restored to 192.168.202.138@tcp (at 192.168.202.138@tcp) [ 1703.407462] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1705.906700] Lustre: DEBUG MARKER: done [ 1708.964726] Lustre: Unmounted lustre-client [ 1736.409597] Key type lgssc unregistered [ 1736.629184] LNet: 42716:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1736.635290] LNetError: 42716:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1737.709640] LNet: Removed LNI 192.168.202.38@tcp [ 1738.460504] Key type .llcrypt unregistered [ 1738.462423] Key type ._llcrypt unregistered [ 1766.258707] Key type ._llcrypt registered [ 1766.261107] Key type .llcrypt registered [ 1767.263817] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 16:56:48 (1788814608) [ 1769.463955] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1771.063833] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 16:56:52 (1788814612) [ 1774.599312] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1774.626449] alg: No test for adler32 (adler32-zlib) [ 1775.741528] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [ 1776.023596] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [ 1777.663228] Key type lgssc registered [ 1778.934565] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1803.289372] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 16:57:24 (1788814644) [ 1834.541127] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1839.875772] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1860.408464] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1863.186358] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 1866.686667] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 1869.770963] Lustre: Unmounted lustre-client [ 1893.841223] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 1937.707208] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1942.895100] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1943.680707] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 1947.363438] Lustre: Unmounted lustre-client [ 2009.208827] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2014.774559] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2015.466759] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 2019.972747] Lustre: Unmounted lustre-client [ 2043.047309] Lustre: DEBUG MARKER: files compared the same [ 2064.564691] Key type lgssc unregistered [ 2064.780237] LNet: 50359:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2064.791646] LNetError: 50359:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2064.810387] LNet: Removed LNI 192.168.202.38@tcp [ 2065.462206] Key type .llcrypt unregistered [ 2065.464223] Key type ._llcrypt unregistered [ 2079.664949] Key type ._llcrypt registered [ 2079.666964] Key type .llcrypt registered [ 2080.849332] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 17:02:01 (1788814921) [ 2083.343124] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2083.418238] alg: No test for adler32 (adler32-zlib) [ 2084.516355] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [ 2084.757671] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [ 2086.463195] Key type lgssc registered [ 2087.552516] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2135.184972] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2140.167539] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2159.637966] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2161.192574] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 2164.428233] Lustre: Unmounted lustre-client [ 2189.184569] Key type lgssc unregistered [ 2189.441873] LNet: 53937:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2189.452765] LNetError: 53937:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2190.505169] LNet: Removed LNI 192.168.202.38@tcp [ 2191.238543] Key type .llcrypt unregistered [ 2191.243646] Key type ._llcrypt unregistered [ 2206.142873] Key type ._llcrypt registered [ 2206.147216] Key type .llcrypt registered [ 2207.451477] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 17:04:08 (1788815048) [ 2209.324482] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2209.457439] alg: No test for adler32 (adler32-zlib) [ 2210.609592] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [ 2210.821212] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [ 2212.471473] Key type lgssc registered [ 2213.781834] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2293.630557] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2320.677255] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2325.304834] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2349.911921] Key type lgssc unregistered [ 2350.112721] LNet: 57418:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2350.116342] LNetError: 57418:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2350.132326] LNet: Removed LNI 192.168.202.38@tcp [ 2350.663827] Key type .llcrypt unregistered [ 2350.665662] Key type ._llcrypt unregistered [ 2362.994980] Key type ._llcrypt registered [ 2362.996412] Key type .llcrypt registered [ 2364.256677] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 17:06:45 (1788815205) [ 2367.451531] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2367.500675] alg: No test for adler32 (adler32-zlib) [ 2368.546568] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [ 2368.793279] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [ 2370.473107] Key type lgssc registered [ 2371.632107] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2433.986557] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 2440.630419] Lustre: Unmounted lustre-client [ 2482.784615] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 17:08:44 (1788815324) [ 2579.316564] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2598.935623] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 2605.758943] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2605.764160] LustreError: 63885:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff9124c4f72000: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 2605.777625] Lustre: 58399:0:(llite_lib.c:4342:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.202.138@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 2616.091029] Lustre: Unmounted lustre-client [ 2653.440833] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 17:11:34 (1788815494) [ 2662.273330] Key type lgssc unregistered [ 2662.452324] LNet: 65097:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2662.457541] LNetError: 65097:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2662.479917] LNet: Removed LNI 192.168.202.38@tcp [ 2663.042992] Key type .llcrypt unregistered [ 2663.052165] Key type ._llcrypt unregistered [ 2671.033255] Key type ._llcrypt registered [ 2671.035036] Key type .llcrypt registered [ 2671.427387] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2671.437258] alg: No test for adler32 (adler32-zlib) [ 2672.431471] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [ 2672.702947] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [ 2674.351289] Key type lgssc registered [ 2675.554644] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2711.376191] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2715.307064] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2729.153765] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2740.272742] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 2743.067553] Lustre: Unmounted lustre-client [ 2775.025502] Key type lgssc unregistered [ 2775.236782] LNet: 68101:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2775.245260] LNetError: 68101:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2776.302691] LNet: Removed LNI 192.168.202.38@tcp [ 2776.855363] Key type .llcrypt unregistered [ 2776.857324] Key type ._llcrypt unregistered [ 2785.516297] Key type ._llcrypt registered [ 2785.520090] Key type .llcrypt registered [ 2785.843790] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2785.854788] alg: No test for adler32 (adler32-zlib) [ 2786.926378] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [ 2787.134990] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [ 2788.799334] Key type lgssc registered [ 2789.835875] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2865.056719] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2868.543350] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2881.345497] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2882.778860] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 2889.202589] Lustre: Unmounted lustre-client [ 2911.303266] Key type lgssc unregistered [ 2911.509912] LNet: 71519:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2911.519179] LNetError: 71519:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2911.539787] LNet: Removed LNI 192.168.202.38@tcp [ 2912.088841] Key type .llcrypt unregistered [ 2912.093055] Key type ._llcrypt unregistered [ 2922.667733] Key type ._llcrypt registered [ 2922.669450] Key type .llcrypt registered [ 2923.646642] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 17:16:04 (1788815764) [ 2925.109176] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2925.157299] alg: No test for adler32 (adler32-zlib) [ 2926.122732] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [ 2926.260673] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [ 2927.863277] Key type lgssc registered [ 2928.566404] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2961.351511] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2965.694431] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2977.973307] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2989.615215] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 2993.563575] Lustre: Unmounted lustre-client [ 2993.848455] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 2995.373570] Lustre: Unmounted lustre-client [ 3017.053953] Key type lgssc unregistered [ 3017.215684] LNet: 75198:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3017.218601] LNetError: 75198:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3017.231758] LNet: Removed LNI 192.168.202.38@tcp [ 3017.780659] Key type .llcrypt unregistered [ 3017.782208] Key type ._llcrypt unregistered [ 3024.773807] Key type ._llcrypt registered [ 3024.776419] Key type .llcrypt registered [ 3025.076617] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3025.086394] alg: No test for adler32 (adler32-zlib) [ 3026.034461] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [ 3026.179856] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [ 3027.807244] Key type lgssc registered [ 3028.616427] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3057.629793] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3060.943587] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3073.490267] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3075.848778] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 3077.947995] Lustre: Unmounted lustre-client [ 3097.767388] Key type lgssc unregistered [ 3097.914951] LNet: 78220:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3097.921357] LNetError: 78220:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3097.936666] LNet: Removed LNI 192.168.202.38@tcp [ 3098.289448] Key type .llcrypt unregistered [ 3098.291951] Key type ._llcrypt unregistered [ 3107.984584] Key type ._llcrypt registered [ 3107.986604] Key type .llcrypt registered [ 3109.087788] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 17:19:10 (1788815950) [ 3112.099902] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3112.117782] alg: No test for adler32 (adler32-zlib) [ 3113.568992] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [ 3114.095455] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [ 3116.023420] Key type lgssc registered [ 3117.499531] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3175.333500] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3181.132109] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3205.068857] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3207.722788] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 3214.373666] Lustre: lustre: root_squash is set to 0:0 [ 3220.058236] Lustre: lustre: nosquash_nids is cleared [ 3225.643623] Lustre: lustre: root_squash is set to 500:500 [ 3235.232522] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.202.38@tcp 192.168.0.[2,10]@tcp [ 3244.476833] Lustre: lustre: nosquash_nids set to 192.168.202.38/24@tcp [ 3248.057459] Lustre: Unmounted lustre-client [ 3274.184705] Key type lgssc unregistered [ 3274.390402] LNet: 82172:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3274.401981] LNetError: 82172:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3274.423616] LNet: Removed LNI 192.168.202.38@tcp [ 3275.102531] Key type .llcrypt unregistered [ 3275.103892] Key type ._llcrypt unregistered [ 3290.628222] Key type ._llcrypt registered [ 3290.633576] Key type .llcrypt registered [ 3291.853826] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 17:22:12 (1788816132) [ 3293.855580] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3295.285613] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 17:22:16 (1788816136) [ 3297.068878] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3297.115421] alg: No test for adler32 (adler32-zlib) [ 3298.219299] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [ 3298.454377] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [ 3300.111275] Key type lgssc registered [ 3301.150553] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3346.436829] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3350.684364] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3367.440027] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3369.001208] Lustre: lustre: root_squash is set to 500:500 [ 3369.022908] Lustre: lustre: nosquash_nids set to 192.168.202.38/24@tcp [ 3370.163765] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 3376.659980] Lustre: Unmounted lustre-client [ 3403.269843] Key type lgssc unregistered [ 3403.483325] LNet: 86130:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3403.493593] LNetError: 86130:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3403.512900] LNet: Removed LNI 192.168.202.38@tcp [ 3404.249816] Key type .llcrypt unregistered [ 3404.258588] Key type ._llcrypt unregistered [ 3417.930478] Key type ._llcrypt registered [ 3417.941956] Key type .llcrypt registered [ 3418.973318] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 3420.443401] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 17:24:21 (1788816261) [ 3436.470140] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3436.509338] alg: No test for adler32 (adler32-zlib) [ 3437.567401] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [ 3437.796051] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [ 3439.447240] Key type lgssc registered [ 3440.476236] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3505.679749] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3509.816603] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3529.561653] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3585.240906] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3590.165323] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3607.043538] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3618.284510] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 3645.008603] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9124d8004000.ost_server_uuid 50 [ 3646.242506] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9124d8004000.ost_server_uuid in FULL state after 0 sec [ 3646.617715] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 3649.214508] Lustre: Unmounted lustre-client [ 3650.331218] Lustre: Unmounted lustre-client [ 3688.038654] Key type lgssc unregistered [ 3688.235054] LNet: 93782:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3688.239636] LNetError: 93782:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3688.251210] LNet: Removed LNI 192.168.202.38@tcp [ 3688.859591] Key type .llcrypt unregistered [ 3688.862402] Key type ._llcrypt unregistered [ 3715.264715] Key type ._llcrypt registered [ 3715.268484] Key type .llcrypt registered [ 3716.524805] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 17:29:17 (1788816557) [ 3730.640930] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3730.680970] alg: No test for adler32 (adler32-zlib) [ 3731.671430] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [ 3731.852775] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [ 3733.487249] Key type lgssc registered [ 3734.272266] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3790.551904] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3793.747558] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3810.585566] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3812.081350] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 3832.735609] Lustre: 95139:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788816659/real 1788816659] req@00000000617ca189 x1875710127903872/t0(0) o400->lustre-OST0000-osc-ffff9124c6600800@192.168.202.138@tcp:28/4 lens 224/224 e 0 to 1 dl 1788816675 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3832.760422] Lustre: lustre-OST0000-osc-ffff9124c6600800: Connection to lustre-OST0000 (at 192.168.202.138@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3835.114322] Lustre: lustre-OST0000-osc-ffff9124c6600800: Connection restored to 192.168.202.138@tcp (at 192.168.202.138@tcp) [ 3837.928792] Lustre: 95139:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788816664/real 1788816664] req@000000001d67b3e7 x1875710127904384/t0(0) o400->lustre-OST0000-osc-ffff9124c6600800@192.168.202.138@tcp:28/4 lens 224/224 e 0 to 1 dl 1788816680 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3837.937745] Lustre: lustre-MDT0000-mdc-ffff9124c6600800: Connection to lustre-MDT0000 (at 192.168.202.138@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3844.063152] Lustre: 95140:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788816670/real 1788816670] req@00000000c4b0d780 x1875710127904896/t0(0) o400->lustre-OST0000-osc-ffff9124c6600800@192.168.202.138@tcp:28/4 lens 224/224 e 0 to 1 dl 1788816686 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3854.303195] Lustre: 95142:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788816680/real 1788816680] req@00000000bbce3f90 x1875710127906048/t0(0) o400->MGC192.168.202.138@tcp@192.168.202.138@tcp:26/25 lens 224/224 e 0 to 1 dl 1788816696 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3854.318610] LustreError: MGC192.168.202.138@tcp: Connection to MGS (at 192.168.202.138@tcp) was lost; in progress operations using this service will fail [ 3854.332265] Lustre: 98306:0:(mgc_request.c:1900:mgc_process_log()) MGC192.168.202.138@tcp: IR log lustre-cliir failed, not fatal: rc = -5 [ 3859.683059] Lustre: lustre-MDT0000-mdc-ffff9124c6600800: Connection restored to 192.168.202.138@tcp (at 192.168.202.138@tcp) [ 3862.010151] Lustre: Unmounted lustre-client [ 3885.687878] Key type lgssc unregistered [ 3885.946935] LNet: 99425:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3885.953876] LNetError: 99425:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3885.964738] LNet: Removed LNI 192.168.202.38@tcp [ 3886.439450] Key type .llcrypt unregistered [ 3886.441096] Key type ._llcrypt unregistered [ 3896.808670] Key type ._llcrypt registered [ 3896.810593] Key type .llcrypt registered [ 3897.925343] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 17:32:19 (1788816739) [ 3911.068614] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3911.104972] alg: No test for adler32 (adler32-zlib) [ 3912.101191] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [ 3912.252204] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [ 3913.887958] Key type lgssc registered [ 3914.707131] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3940.910828] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3998.728445] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4002.496276] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4018.640319] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4020.107763] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 4021.531255] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 4022.658715] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 17:34:24 (1788816864) [ 4175.023706] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4185.361207] Lustre: Unmounted lustre-client [ 4266.973315] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4270.705431] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4286.725804] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4317.631380] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 17:39:19 (1788817159) [ 4375.112606] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4378.771468] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4394.623941] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4395.058554] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 4398.093710] Lustre: Unmounted lustre-client [ 4423.963318] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 17:41:05 (1788817265) [ 4478.891387] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4483.528648] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4510.344832] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4510.841396] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 4517.828653] Lustre: Unmounted lustre-client [ 4550.537452] Key type lgssc unregistered [ 4550.815827] LNet: 122412:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4550.822234] LNetError: 122412:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4550.848089] LNet: Removed LNI 192.168.202.38@tcp [ 4551.640356] Key type .llcrypt unregistered [ 4551.645581] Key type ._llcrypt unregistered [ 4568.778919] Key type ._llcrypt registered [ 4568.788756] Key type .llcrypt registered [ 4570.717814] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 17:43:30 (1788817410) [ 4573.956080] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4574.001595] alg: No test for adler32 (adler32-zlib) [ 4575.176261] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [ 4575.477726] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [ 4577.329724] Key type lgssc registered [ 4579.356269] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4630.548980] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4636.487905] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4660.278984] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4663.163874] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 4677.364820] Lustre: Unmounted lustre-client [ 4707.190614] Key type lgssc unregistered [ 4707.512250] LNet: 125999:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4707.519033] LNetError: 125999:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4707.528828] LNet: Removed LNI 192.168.202.38@tcp [ 4708.023960] Key type .llcrypt unregistered [ 4708.025355] Key type ._llcrypt unregistered [ 4726.165259] Key type ._llcrypt registered [ 4726.169809] Key type .llcrypt registered [ 4727.804771] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 17:46:08 (1788817568) [ 4730.396894] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4730.427164] alg: No test for adler32 (adler32-zlib) [ 4731.613387] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [ 4731.938813] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [ 4733.647252] Key type lgssc registered [ 4734.840372] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4786.360407] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4792.654543] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4815.778697] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4818.678985] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 4829.192874] Lustre: lustre-OST0000-osc-ffff9124c52b2800: Connection to lustre-OST0000 (at 192.168.202.138@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4840.278803] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff9124c52b2800.ost_server_uuid 50 [ 4842.296376] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 4854.207831] Lustre: Unmounted lustre-client [ 4875.183291] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 17:48:36 (1788817716) [ 4909.670949] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4914.589299] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4936.772166] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4960.871456] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4961.469557] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 4966.916285] Lustre: lustre-OST0000-osc-ffff9124c783a000: Connection to lustre-OST0000 (at 192.168.202.138@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4992.917432] Lustre: Unmounted lustre-client [ 5054.139949] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 17:51:34 (1788817894) [ 5094.056945] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5101.755521] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5128.962793] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5153.262625] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5154.603272] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 5159.920950] Lustre: lustre-OST0000-osc-ffff9124c2dc7800: Connection to lustre-OST0000 (at 192.168.202.138@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5177.489839] Lustre: Unmounted lustre-client [ 5223.764638] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 17:54:25 (1788818065) [ 5319.085263] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5325.217750] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5347.570303] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5425.593344] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5432.405230] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5455.543029] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5495.305344] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 5542.459576] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5544.383920] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 5547.240740] Lustre: Unmounted lustre-client [ 5580.056610] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 18:00:20 (1788818420) [ 5610.695862] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5616.903200] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5639.732269] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5674.825643] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5714.258747] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 5764.955212] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5767.022552] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 5784.325241] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5795.120408] Lustre: Unmounted lustre-client [ 5862.640390] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 18:05:03 (1788818703) [ 5897.714208] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5904.340645] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5927.598939] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5928.243029] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 5951.399312] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5952.775751] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 5967.870513] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9124d8a06000.ost_server_uuid 50 [ 5969.732724] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9124d8a06000.ost_server_uuid in IDLE state after 0 sec [ 5973.891701] Lustre: Unmounted lustre-client [ 5974.253518] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 5974.267270] Lustre: Skipped 3 previous similar messages [ 5974.382724] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 5975.282656] LustreError: 149440:0:(sec.c:409:import_sec_validate_get()) import 000000001553fc61 (NEW) with no sec [ 6046.277581] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 18:08:07 (1788818887) [ 6086.607413] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6093.567953] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6115.575529] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6136.888883] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6137.381339] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 6137.516754] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 6153.061622] Lustre: Unmounted lustre-client [ 6153.069482] Lustre: Skipped 1 previous similar message [ 6191.874432] Key type lgssc unregistered [ 6192.086397] LNet: 152866:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6192.089644] LNetError: 152866:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6192.116362] LNet: Removed LNI 192.168.202.38@tcp [ 6192.713199] Key type .llcrypt unregistered [ 6192.714826] Key type ._llcrypt unregistered [ 6209.330761] Key type ._llcrypt registered [ 6209.332870] Key type .llcrypt registered [ 6211.060635] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 18:10:51 (1788819051) [ 6212.175319] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6212.281291] alg: No test for adler32 (adler32-zlib) [ 6213.590094] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [ 6214.064730] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [ 6215.951360] Key type lgssc registered [ 6218.005555] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6268.464483] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6273.812367] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6292.675592] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6315.097289] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6315.881235] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 6317.007243] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 6322.297894] LustreError: lustre-MDT0001-mdc-ffff9124c72a9800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 6322.327282] Lustre: lustre-MDT0001-mdc-ffff9124c72a9800: Connection restored to 192.168.202.138@tcp (at 192.168.202.138@tcp) [ 6329.993884] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6331.820827] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6354.958463] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 6358.915804] Lustre: Unmounted lustre-client [ 6399.518766] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 18:14:00 (1788819240) [ 6497.320655] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6502.763861] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6527.140203] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6527.727772] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 6558.093845] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6559.922523] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6574.841542] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6585.211803] Lustre: Unmounted lustre-client [ 6634.063230] Key type lgssc unregistered [ 6634.350932] LNet: 162483:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6634.368778] LNetError: 162483:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6634.381357] LNet: Removed LNI 192.168.202.38@tcp [ 6635.196200] Key type .llcrypt unregistered [ 6635.197748] Key type ._llcrypt unregistered [ 6682.841961] Key type ._llcrypt registered [ 6682.845962] Key type .llcrypt registered [ 6685.183978] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 18:18:45 (1788819525) [ 6688.675097] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6688.845831] alg: No test for adler32 (adler32-zlib) [ 6689.908738] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [ 6690.117643] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [ 6691.767863] Key type lgssc registered [ 6693.667485] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6754.488806] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6760.832134] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6783.113709] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6785.499485] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 6832.677445] Lustre: Unmounted lustre-client [ 6873.101806] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6873.607879] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 6909.803936] Lustre: Unmounted lustre-client [ 6939.762639] Key type lgssc unregistered [ 6939.983535] LNet: 166933:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6939.989108] LNetError: 166933:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6940.004153] LNet: Removed LNI 192.168.202.38@tcp [ 6940.678868] Key type .llcrypt unregistered [ 6940.685212] Key type ._llcrypt unregistered [ 6957.981932] Key type ._llcrypt registered [ 6957.984140] Key type .llcrypt registered [ 6958.911966] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 18:23:20 (1788819800) [ 6961.333481] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6961.472850] alg: No test for adler32 (adler32-zlib) [ 6962.497968] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [ 6962.698415] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [ 6964.359246] Key type lgssc registered [ 6965.290286] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7010.241330] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7016.347185] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7040.137275] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7043.628162] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 7066.680446] Lustre: Unmounted lustre-client [ 7096.510654] Key type lgssc unregistered [ 7096.808866] LNet: 170682:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7096.816768] LNetError: 170682:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7096.851890] LNet: Removed LNI 192.168.202.38@tcp [ 7097.504486] Key type .llcrypt unregistered [ 7097.506737] Key type ._llcrypt unregistered [ 7111.008497] Key type ._llcrypt registered [ 7111.017907] Key type .llcrypt registered [ 7111.566855] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7111.585203] alg: No test for adler32 (adler32-zlib) [ 7112.638939] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [ 7112.886380] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [ 7114.647687] Key type lgssc registered [ 7116.022229] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7171.980871] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7179.420898] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7200.788246] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7216.734832] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 7226.710018] Lustre: Unmounted lustre-client [ 7257.751212] Key type lgssc unregistered [ 7258.042983] LNet: 173748:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7258.061221] LNetError: 173748:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7258.079723] LNet: Removed LNI 192.168.202.38@tcp [ 7258.734223] Key type .llcrypt unregistered [ 7258.742794] Key type ._llcrypt unregistered [ 7275.386659] Key type ._llcrypt registered [ 7275.393301] Key type .llcrypt registered [ 7276.991902] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 18:28:37 (1788820117) [ 7279.454331] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7279.467964] alg: No test for adler32 (adler32-zlib) [ 7280.641629] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [ 7280.932884] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [ 7282.591422] Key type lgssc registered [ 7284.108433] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7338.803687] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7345.305601] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7373.077560] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7374.815109] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 7399.979557] Lustre: Unmounted lustre-client [ 7430.166816] Key type lgssc unregistered [ 7430.468930] LNet: 177502:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7430.486639] LNetError: 177502:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7430.521475] LNet: Removed LNI 192.168.202.38@tcp [ 7431.614548] Key type .llcrypt unregistered [ 7431.623132] Key type ._llcrypt unregistered [ 7444.928156] Key type ._llcrypt registered [ 7444.930021] Key type .llcrypt registered [ 7445.317782] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7445.328581] alg: No test for adler32 (adler32-zlib) [ 7446.505142] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [ 7446.780761] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [ 7448.464495] Key type lgssc registered [ 7449.544339] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7500.202089] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7506.186474] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7527.177981] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7528.966672] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 7539.784047] Lustre: Unmounted lustre-client [ 7575.602887] Key type lgssc unregistered [ 7575.908801] LNet: 180567:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7575.929809] LNetError: 180567:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7575.949500] LNet: Removed LNI 192.168.202.38@tcp [ 7576.694130] Key type .llcrypt unregistered [ 7576.701067] Key type ._llcrypt unregistered [ 7593.547648] Key type ._llcrypt registered [ 7593.552978] Key type .llcrypt registered [ 7595.237001] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 18:33:55 (1788820435) [ 7620.692314] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7620.709122] alg: No test for adler32 (adler32-zlib) [ 7621.960632] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [ 7622.244412] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [ 7623.903993] Key type lgssc registered [ 7625.232403] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7715.070417] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7723.190826] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7745.702494] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7787.710898] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 18:37:08 (1788820628) [ 7821.740554] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7829.344144] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7851.681320] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7854.272287] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 7859.154731] Lustre: Unmounted lustre-client [ 7893.141863] Key type lgssc unregistered [ 7893.455961] LNet: 187668:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7893.464140] LNetError: 187668:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7893.504382] LNet: Removed LNI 192.168.202.38@tcp [ 7894.173294] Key type .llcrypt unregistered [ 7894.175649] Key type ._llcrypt unregistered [ 7907.716179] Key type ._llcrypt registered [ 7907.718868] Key type .llcrypt registered [ 7909.044344] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 7910.293504] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 18:39:11 (1788820751) [ 7928.456341] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7928.484943] alg: No test for adler32 (adler32-zlib) [ 7929.517265] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [ 7929.693169] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [ 7931.335679] Key type lgssc registered [ 7932.436188] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8012.907841] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8019.439499] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8045.404925] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8064.279772] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8065.977565] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 8085.925900] Lustre: Unmounted lustre-client [ 8231.277362] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8237.186594] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8260.755283] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8303.852236] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 18:45:44 (1788821144) [ 8305.853847] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 8307.677704] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 18:45:48 (1788821148) [ 8414.648664] Key type lgssc unregistered [ 8414.845606] LNet: 199117:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8414.851055] LNetError: 199117:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8414.869361] LNet: Removed LNI 192.168.202.38@tcp [ 8415.608786] Key type .llcrypt unregistered [ 8415.611403] Key type ._llcrypt unregistered [ 8431.216891] Key type ._llcrypt registered [ 8431.219603] Key type .llcrypt registered [ 8432.439887] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 18:47:53 (1788821273) [ 8474.610986] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8474.641686] alg: No test for adler32 (adler32-zlib) [ 8475.840782] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [ 8476.147365] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [ 8477.903241] Key type lgssc registered [ 8479.358616] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8538.008903] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8546.094610] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8585.038891] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8622.653754] Key type lgssc unregistered [ 8623.027948] LNet: 202851:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8623.040720] LNetError: 202851:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8623.058536] LNet: Removed LNI 192.168.202.38@tcp [ 8624.171737] Key type .llcrypt unregistered [ 8624.174905] Key type ._llcrypt unregistered [ 8643.766346] Key type ._llcrypt registered [ 8643.769762] Key type .llcrypt registered [ 8645.038833] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 18:51:26 (1788821486) [ 8648.446048] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8648.544189] alg: No test for adler32 (adler32-zlib) [ 8649.844867] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [ 8650.333345] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [ 8652.223267] Key type lgssc registered [ 8654.377663] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8706.017481] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8710.766381] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8731.101990] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8732.642259] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 8737.768287] Lustre: lustre-MDT0000-mdc-ffff9124c570f000: Connection to lustre-MDT0000 (at 192.168.202.138@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8748.014218] Lustre: lustre-MDT0001-mdc-ffff9124c570f000: Connection to lustre-MDT0001 (at 192.168.202.138@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8764.322881] Lustre: 203841:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788821590/real 1788821590] req@0000000093bd12f2 x1875715284877312/t0(0) o400->MGC192.168.202.138@tcp@192.168.202.138@tcp:26/25 lens 224/224 e 0 to 1 dl 1788821606 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 8764.370191] LustreError: MGC192.168.202.138@tcp: Connection to MGS (at 192.168.202.138@tcp) was lost; in progress operations using this service will fail [ 8774.640919] Lustre: Evicted from MGS (at 192.168.202.138@tcp) after server handle changed from 0x7c0b091d2a68ce26 to 0x7c0b091d2a68ea1f [ 8774.671863] Lustre: MGC192.168.202.138@tcp: Connection restored to 192.168.202.138@tcp (at 192.168.202.138@tcp) [ 8794.114303] LustreError: lustre-MDT0000-mdc-ffff9124c570f000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 8794.262161] Lustre: lustre-MDT0000-mdc-ffff9124c570f000: Connection restored to 192.168.202.138@tcp (at 192.168.202.138@tcp) [ 8808.181363] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8809.607573] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 8815.824582] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8818.002303] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 8822.361343] Lustre: Unmounted lustre-client [ 8850.515923] Key type lgssc unregistered [ 8850.870668] LNet: 207790:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8850.887535] LNetError: 207790:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8850.927128] LNet: Removed LNI 192.168.202.38@tcp [ 8851.900268] Key type .llcrypt unregistered [ 8851.903769] Key type ._llcrypt unregistered [ 8869.378622] Key type ._llcrypt registered [ 8869.380389] Key type .llcrypt registered [ 8870.444909] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 18:55:11 (1788821711) [ 8873.542403] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8873.547742] alg: No test for adler32 (adler32-zlib) [ 8874.880861] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [ 8875.177566] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [ 8876.903301] Key type lgssc registered [ 8878.460945] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8921.543427] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8926.683396] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8976.682751] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8982.346814] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9017.652599] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9039.618258] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9084.562711] Key type lgssc unregistered [ 9084.842680] LNet: 213078:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9084.848673] LNetError: 213078:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9084.873677] LNet: Removed LNI 192.168.202.38@tcp [ 9085.688936] Key type .llcrypt unregistered [ 9085.690840] Key type ._llcrypt unregistered [ 9132.346122] Key type ._llcrypt registered [ 9132.347577] Key type .llcrypt registered [ 9133.558946] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 18:59:34 (1788821974) [ 9172.507836] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9172.746550] alg: No test for adler32 (adler32-zlib) [ 9174.113071] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [ 9174.452194] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [ 9176.215375] Key type lgssc registered [ 9177.685256] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9271.090888] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9277.131801] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9301.637707] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9341.993867] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 19:03:03 (1788822183) [ 9349.548711] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 19:03:10 (1788822190) [ 9385.601050] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9390.520608] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9412.334405] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9414.152956] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 9422.935456] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [ 9424.672303] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 9426.494531] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 9428.239393] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [ 9434.609529] Lustre: lustre-MDT0000-mdc-ffff9124c72af000: Connection to lustre-MDT0000 (at 192.168.202.138@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9449.951887] Lustre: 214970:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788822276/real 1788822276] req@000000002dd6ff95 x1875715834259584/t0(0) o400->MGC192.168.202.138@tcp@192.168.202.138@tcp:26/25 lens 224/224 e 0 to 1 dl 1788822292 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9449.957565] LustreError: MGC192.168.202.138@tcp: Connection to MGS (at 192.168.202.138@tcp) was lost; in progress operations using this service will fail [ 9449.996329] Lustre: Evicted from MGS (at 192.168.202.138@tcp) after server handle changed from 0x24c4e26342973e0f to 0x24c4e26342973f89 [ 9450.002956] Lustre: MGC192.168.202.138@tcp: Connection restored to 192.168.202.138@tcp (at 192.168.202.138@tcp) [ 9457.644845] Lustre: 220974:0:(mgc_request.c:1900:mgc_process_log()) MGC192.168.202.138@tcp: IR log lustre-cliir failed, not fatal: rc = -2 [ 9463.577430] Lustre: lustre-MDT0000-mdc-ffff9124c72af000: Connection restored to 192.168.202.138@tcp (at 192.168.202.138@tcp) [ 9471.932857] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9473.476419] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9477.253471] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [ 9480.690107] Lustre: lustre-MDT0000-mdc-ffff9124c72af000: Connection to lustre-MDT0000 (at 192.168.202.138@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9497.055168] Lustre: 214970:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788822323/real 1788822323] req@0000000024d49ce3 x1875715834268160/t0(0) o400->MGC192.168.202.138@tcp@192.168.202.138@tcp:26/25 lens 224/224 e 0 to 1 dl 1788822339 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9497.082189] LustreError: MGC192.168.202.138@tcp: Connection to MGS (at 192.168.202.138@tcp) was lost; in progress operations using this service will fail [ 9504.547164] Lustre: lustre-MDT0000-mdc-ffff9124c72af000: Connection restored to 192.168.202.138@tcp (at 192.168.202.138@tcp) [ 9507.628456] Lustre: Evicted from MGS (at 192.168.202.138@tcp) after server handle changed from 0x24c4e26342973f89 to 0x24c4e26342974516 [ 9507.638739] Lustre: MGC192.168.202.138@tcp: Connection restored to 192.168.202.138@tcp (at 192.168.202.138@tcp) [ 9513.865265] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9515.958574] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9520.841191] Lustre: Unmounted lustre-client [ 9587.736908] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9593.393904] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9613.927576] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9614.485370] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 9616.665675] Lustre: Unmounted lustre-client [ 9651.345982] Key type lgssc unregistered [ 9651.974225] LNet: 225562:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9651.992360] LNetError: 225562:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9652.020252] LNet: Removed LNI 192.168.202.38@tcp [ 9653.390588] Key type .llcrypt unregistered [ 9653.392261] Key type ._llcrypt unregistered [ 9672.903622] Key type ._llcrypt registered [ 9672.906510] Key type .llcrypt registered [ 9674.378578] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 19:08:35 (1788822515) [ 9678.354944] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9678.441054] alg: No test for adler32 (adler32-zlib) [ 9679.616772] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [ 9679.869101] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [ 9681.544405] Key type lgssc registered [ 9682.976284] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9733.318651] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9739.237714] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9762.298967] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9763.971401] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 9773.426744] Lustre: Unmounted lustre-client [ 9842.703075] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9850.709782] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9874.572511] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9885.759911] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 9895.664191] Lustre: Unmounted lustre-client [ 9960.700811] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9965.723484] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9986.959294] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9987.519387] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [ 9989.055208] Lustre: Unmounted lustre-client [10017.436344] Key type lgssc unregistered [10017.822598] LNet: 232609:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10017.840534] LNetError: 232609:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10017.870176] LNet: Removed LNI 192.168.202.38@tcp [10018.654957] Key type .llcrypt unregistered [10018.656563] Key type ._llcrypt unregistered [10034.869787] Key type ._llcrypt registered [10034.873477] Key type .llcrypt registered [10036.366194] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 19:14:37 (1788822877) [10039.562388] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10039.578079] alg: No test for adler32 (adler32-zlib) [10040.632553] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [10040.800835] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [10042.471411] Key type lgssc registered [10043.971652] Lustre: Echo OBD driver; http://www.lustre.org/ [10092.829098] Key type lgssc unregistered [10093.322573] LNet: 234927:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10093.336742] LNetError: 234927:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10093.358399] LNet: Removed LNI 192.168.202.38@tcp [10094.385755] Key type .llcrypt unregistered [10094.389376] Key type ._llcrypt unregistered [10123.561229] Key type ._llcrypt registered [10123.562963] Key type .llcrypt registered [10124.088649] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10124.124714] alg: No test for adler32 (adler32-zlib) [10125.329024] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [10125.657995] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [10127.368186] Key type lgssc registered [10128.699240] Lustre: Echo OBD driver; http://www.lustre.org/ [10223.800137] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10230.177409] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10262.227706] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10313.059517] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [10314.695687] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 19:19:15 (1788823155) [10351.920991] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10358.900851] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10385.210535] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10412.163502] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10414.252665] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [10419.711751] Lustre: lustre-OST0001-osc-ffff9124d8003000: Connection to lustre-OST0001 (at 192.168.202.138@tcp) was lost; in progress operations using this service will wait for recovery to complete [10428.462320] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [10438.846212] Lustre: Unmounted lustre-client [10475.149338] Key type lgssc unregistered [10475.416190] LNet: 242046:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10475.419976] LNetError: 242046:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10475.442628] LNet: Removed LNI 192.168.202.38@tcp [10476.364791] Key type .llcrypt unregistered [10476.373561] Key type ._llcrypt unregistered [10517.578736] Key type ._llcrypt registered [10517.584149] Key type .llcrypt registered [10519.148041] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 19:22:39 (1788823359) [10534.126544] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10534.154570] alg: No test for adler32 (adler32-zlib) [10535.246445] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [10535.487710] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [10537.175938] Key type lgssc registered [10538.211876] Lustre: Echo OBD driver; http://www.lustre.org/ [10596.938765] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10603.696832] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10634.616381] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 19:24:35 (1788823475) [10674.331314] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10681.805281] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10706.555186] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10708.389245] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [10718.530086] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [10726.209320] Lustre: Unmounted lustre-client [10841.193112] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10849.445099] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10902.678768] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10903.133394] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [10905.589375] Lustre: Unmounted lustre-client [10928.360399] Key type lgssc unregistered [10928.592533] LNet: 249729:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10928.607773] LNetError: 249729:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10928.625217] LNet: Removed LNI 192.168.202.38@tcp [10929.320662] Key type .llcrypt unregistered [10929.325165] Key type ._llcrypt unregistered [10963.303960] Key type ._llcrypt registered [10963.305602] Key type .llcrypt registered [10963.601713] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10963.616567] alg: No test for adler32 (adler32-zlib) [10965.000504] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [10965.396889] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [10967.322194] Key type lgssc registered [10969.020545] Lustre: Echo OBD driver; http://www.lustre.org/ [11067.960427] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11075.765913] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11101.230165] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11146.986299] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [11148.963024] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 19:33:09 (1788823989) [11185.112974] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11190.534423] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11213.082301] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11219.483202] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [11224.246974] Lustre: Unmounted lustre-client [11255.805393] Key type lgssc unregistered [11256.171955] LNet: 256364:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11256.184960] LNetError: 256364:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11256.205785] LNet: Removed LNI 192.168.202.38@tcp [11256.971093] Key type .llcrypt unregistered [11256.972836] Key type ._llcrypt unregistered [11275.337312] Key type ._llcrypt registered [11275.341523] Key type .llcrypt registered [11277.179752] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [11278.940519] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 19:35:19 (1788824119) [11289.232527] Key type .llcrypt unregistered [11289.235073] Key type ._llcrypt unregistered [11300.241108] Key type ._llcrypt registered [11300.246022] Key type .llcrypt registered [11301.008069] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11301.034237] alg: No test for adler32 (adler32-zlib) [11302.228497] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [11302.462216] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [11304.159297] Key type lgssc registered [11305.708389] Lustre: Echo OBD driver; http://www.lustre.org/ [11366.436526] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11385.076575] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [11386.884411] Lustre: Unmounted lustre-client [11420.312368] Key type lgssc unregistered [11420.577540] LNet: 259815:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11420.587240] LNetError: 259815:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11420.606798] LNet: Removed LNI 192.168.202.38@tcp [11421.732895] Key type .llcrypt unregistered [11421.737674] Key type ._llcrypt unregistered [11442.662930] Key type ._llcrypt registered [11442.668032] Key type .llcrypt registered [11444.548782] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 19:38:04 (1788824284) [11447.655335] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11447.709088] alg: No test for adler32 (adler32-zlib) [11448.972148] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [11449.270984] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [11450.975458] Key type lgssc registered [11452.305641] Lustre: Echo OBD driver; http://www.lustre.org/ [11536.716354] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11569.215160] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11574.696838] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11577.457632] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [11580.566490] Lustre: Unmounted lustre-client [11598.587492] Key type lgssc unregistered [11598.856926] LNet: 263351:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11598.867793] LNetError: 263351:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11598.877125] LNet: Removed LNI 192.168.202.38@tcp [11599.811914] Key type .llcrypt unregistered [11599.819518] Key type ._llcrypt unregistered [11613.665601] Key type ._llcrypt registered [11613.667710] Key type .llcrypt registered [11614.691059] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 19:40:55 (1788824455) [11616.491264] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11616.595968] alg: No test for adler32 (adler32-zlib) [11617.673425] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [11617.899717] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [11619.537752] Key type lgssc registered [11620.614460] Lustre: Echo OBD driver; http://www.lustre.org/ [11658.614500] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11663.163685] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11678.871029] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11689.543143] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [11694.564454] Lustre: lustre-MDT0000-mdc-ffff9124c71b0800: Connection to lustre-MDT0000 (at 192.168.202.138@tcp) was lost; in progress operations using this service will wait for recovery to complete [11699.120679] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11699.145362] LustreError: 266502:0:(file.c:6167:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [11699.522583] LustreError: MGC192.168.202.138@tcp: Connection to MGS (at 192.168.202.138@tcp) was lost; in progress operations using this service will fail [11699.575250] Lustre: Unmounted lustre-client [11716.568705] Key type lgssc unregistered [11716.768960] LNet: 266913:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11716.775860] LNetError: 266913:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11717.800376] LNet: Removed LNI 192.168.202.38@tcp [11718.333124] Key type .llcrypt unregistered [11718.334232] Key type ._llcrypt unregistered [11730.040303] Key type ._llcrypt registered [11730.042145] Key type .llcrypt registered [11731.069232] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 19:42:52 (1788824572) [11732.706693] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11732.716905] alg: No test for adler32 (adler32-zlib) [11733.719205] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [11733.914528] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [11735.559232] Key type lgssc registered [11736.315158] Lustre: Echo OBD driver; http://www.lustre.org/ [11770.551704] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11775.241089] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11793.045246] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11799.093902] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [11804.145654] Lustre: lustre-MDT0001-mdc-ffff9124c4f76800: Connection to lustre-MDT0001 (at 192.168.202.138@tcp) was lost; in progress operations using this service will wait for recovery to complete [11816.712262] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [11818.470277] Lustre: Unmounted lustre-client [11835.842324] Key type lgssc unregistered [11836.053584] LNet: 270483:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11836.058244] LNetError: 270483:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11836.084843] LNet: Removed LNI 192.168.202.38@tcp [11836.781364] Key type .llcrypt unregistered [11836.784479] Key type ._llcrypt unregistered [11849.457854] Key type ._llcrypt registered [11849.461561] Key type .llcrypt registered [11850.607709] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 19:44:51 (1788824691) [11864.906941] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11864.914051] alg: No test for adler32 (adler32-zlib) [11865.901723] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [11866.092455] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [11867.747769] Key type lgssc registered [11868.593272] Lustre: Echo OBD driver; http://www.lustre.org/ [11948.424908] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11949.908305] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [11977.197212] Lustre: lustre-MDT0001-mdc-ffff9124c5b47800: Connection to lustre-MDT0001 (at 192.168.202.138@tcp) was lost; in progress operations using this service will wait for recovery to complete [11984.016653] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11994.302744] Lustre: Unmounted lustre-client [12018.413829] Key type lgssc unregistered [12018.606852] LNet: 276254:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12018.612137] LNetError: 276254:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12018.629074] LNet: Removed LNI 192.168.202.38@tcp [12019.169728] Key type .llcrypt unregistered [12019.174322] Key type ._llcrypt unregistered [12029.959691] Key type ._llcrypt registered [12029.961402] Key type .llcrypt registered [12030.858930] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 19:47:52 (1788824872) [12032.005339] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [12033.204136] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 19:47:54 (1788824874) [12034.352327] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [12035.620791] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 19:47:56 (1788824876) [12036.697405] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [12037.862449] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 19:47:59 (1788824879) [12038.855826] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [12040.113750] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 19:48:01 (1788824881) [12041.159968] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [12042.348881] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 19:48:03 (1788824883) [12048.793718] Key type .llcrypt unregistered [12048.796698] Key type ._llcrypt unregistered [12054.848861] Key type ._llcrypt registered [12054.854288] Key type .llcrypt registered [12055.217359] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12055.232016] alg: No test for adler32 (adler32-zlib) [12056.245928] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [12056.438308] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [12058.095292] Key type lgssc registered [12058.956497] Lustre: Echo OBD driver; http://www.lustre.org/ [12110.848889] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12115.372630] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12131.410652] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12133.943257] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [12135.577842] Lustre: Unmounted lustre-client [12166.216429] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 19:50:07 (1788825007) [12193.406548] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12198.358543] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12229.174938] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12245.050111] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [12246.311291] Lustre: Unmounted lustre-client [12279.904604] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 19:52:01 (1788825121) [12280.735703] LNet: 1 local NIs in recovery (showing 1): 192.168.202.38@tcp [12897.154215] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12901.785207] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12929.803692] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13002.211271] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 20:04:03 (1788825843) [13009.029382] Key type lgssc unregistered [13009.213804] LNet: 289764:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13009.218095] LNetError: 289764:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13009.229289] LNet: Removed LNI 192.168.202.38@tcp [13009.605574] Key type .llcrypt unregistered [13009.608986] Key type ._llcrypt unregistered [13144.671967] Key type ._llcrypt registered [13144.674497] Key type .llcrypt registered [13144.907328] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13144.915571] alg: No test for adler32 (adler32-zlib) [13145.935665] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [13146.072971] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [13147.687248] Key type lgssc registered [13148.424193] Lustre: Echo OBD driver; http://www.lustre.org/ [13193.506484] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13195.767274] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13204.985530] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13224.787922] Key type lgssc unregistered [13224.942514] LNet: 293443:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13224.947404] LNetError: 293443:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13224.959213] LNet: Removed LNI 192.168.202.38@tcp [13225.242877] Key type .llcrypt unregistered [13225.244196] Key type ._llcrypt unregistered [13250.159157] Key type ._llcrypt registered [13250.160613] Key type .llcrypt registered [13250.712938] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 20:08:12 (1788826092) [13258.917687] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13258.961339] alg: No test for adler32 (adler32-zlib) [13259.862436] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [13259.992316] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [13261.607226] Key type lgssc registered [13262.185768] Lustre: Echo OBD driver; http://www.lustre.org/ [13286.809367] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 20:08:48 (1788826128) [13292.521402] Key type lgssc unregistered [13292.663637] LNet: 296985:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13292.666486] LNetError: 296985:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13292.675603] LNet: Removed LNI 192.168.202.38@tcp [13293.082695] Key type .llcrypt unregistered [13293.084758] Key type ._llcrypt unregistered [13297.677568] Key type ._llcrypt registered [13297.679115] Key type .llcrypt registered [13297.800424] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13297.809741] alg: No test for adler32 (adler32-zlib) [13298.716678] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [13298.822569] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [13300.431239] Key type lgssc registered [13300.869523] Lustre: Echo OBD driver; http://www.lustre.org/ [13328.399345] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13330.839413] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13341.012245] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13542.421836] LNet: Added LNI 192.168.202.38@tcp50 [8/256/0/180] [13544.146979] LNet: Removed LNI 192.168.202.38@tcp [13546.202874] Lustre: 301355:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff9124c46a9800: config has no valid NIDs, force dynamic NIDs [13547.360042] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [13547.466530] Lustre: Unmounted lustre-client [13549.322230] Key type lgssc unregistered [13549.665581] LNet: 301612:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13549.681866] LNetError: 301612:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13549.699160] LNet: Removed LNI 192.168.202.38@tcp50 [13550.203662] Key type .llcrypt unregistered [13550.205330] Key type ._llcrypt unregistered [13561.208610] Key type ._llcrypt registered [13561.212909] Key type .llcrypt registered [13561.546603] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13561.555965] alg: No test for adler32 (adler32-zlib) [13562.545935] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [13562.699160] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [13564.335237] Key type lgssc registered [13565.292038] Lustre: Echo OBD driver; http://www.lustre.org/ [13599.860298] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13603.203735] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13608.140121] LNet: Added LNI 192.168.202.38@tcp50 [8/256/0/180] [13608.690697] LNet: Removed LNI 192.168.202.38@tcp [13609.891172] Lustre: 304292:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff9124c2dc4800: config has no valid NIDs, force dynamic NIDs [13611.044341] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [13611.439140] Lustre: Unmounted lustre-client [13635.091591] Key type lgssc unregistered [13635.377510] LNet: 305158:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13635.382205] LNetError: 305158:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13635.407199] LNet: Removed LNI 192.168.202.38@tcp50 [13635.811699] Key type .llcrypt unregistered [13635.814837] Key type ._llcrypt unregistered [13644.053539] Key type ._llcrypt registered [13644.055629] Key type .llcrypt registered [13644.947275] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 20:14:46 (1788826486) [13657.916339] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13658.053770] alg: No test for adler32 (adler32-zlib) [13659.030815] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [13659.224539] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [13660.848528] Key type lgssc registered [13661.744240] Lustre: Echo OBD driver; http://www.lustre.org/ [13719.169924] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13723.777813] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13744.067630] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13770.633879] Key type lgssc unregistered [13770.829785] LNet: 309890:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13770.838480] LNetError: 309890:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13770.847115] LNet: Removed LNI 192.168.202.38@tcp [13771.488058] Key type .llcrypt unregistered [13771.496170] Key type ._llcrypt unregistered [13780.233937] Key type ._llcrypt registered [13780.237106] Key type .llcrypt registered [13781.306196] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 20:17:02 (1788826622) [13783.191679] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13783.203391] alg: No test for adler32 (adler32-zlib) [13784.153839] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [13784.329609] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [13785.943231] Key type lgssc registered [13786.799210] Lustre: Echo OBD driver; http://www.lustre.org/ [13823.468129] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13827.790591] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13843.789751] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13854.732663] Lustre: Mounted lustre-client - version 2.17.58_41_g50dbf09 [13901.264873] Lustre: Mounted fs15246-client - version 2.17.58_41_g50dbf09 [13912.745115] Lustre: Unmounted fs15246-client [13934.973315] Lustre: Unmounted lustre-client [13960.930544] Key type lgssc unregistered [13961.172707] LNet: 314743:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13961.181456] LNetError: 314743:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13961.200793] LNet: Removed LNI 192.168.202.38@tcp [13961.796681] Key type .llcrypt unregistered [13961.798913] Key type ._llcrypt unregistered [13971.214079] Key type ._llcrypt registered [13971.216100] Key type .llcrypt registered [13972.412917] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 20:20:13 (1788826813) [13973.734843] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13973.789547] alg: No test for adler32 (adler32-zlib) [13974.839272] Lustre: Lustre: Build Version: 2.17.58_41_g50dbf09 [13975.048062] LNet: Added LNI 192.168.202.38@tcp [8/256/0/180] [13976.679250] Key type lgssc registered [13977.789403] Lustre: Echo OBD driver; http://www.lustre.org/ [14081.497327] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14086.159531] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14102.340701] Lustre: DEBUG MARKER: oleg238-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14172.916020] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 13974 sec ========= 20:23:34 (1788827014) [14174.198650] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 20:23:35 (1788827015) === [14176.663899] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 20:23:38 (1788827018) === [14201.297126] Key type lgssc unregistered [14201.489594] LNet: 323988:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14201.497385] LNetError: 323988:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14201.513758] LNet: Removed LNI 192.168.202.38@tcp [14202.026979] Key type .llcrypt unregistered [14202.029758] Key type ._llcrypt unregistered