[ 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 540819807 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2400.000 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2464MB 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.001017] APIC: Switch to symmetric I/O mode setup [ 0.003316] x2apic enabled [ 0.005005] Switched APIC routing to physical x2apic. [ 0.006016] kvm-guest: setup PV IPIs [ 0.008923] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.009000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x22983777dd9, max_idle_ns: 440795300422 ns [ 0.009029] Calibrating delay loop (skipped) preset value.. 4800.00 BogoMIPS (lpj=2400000) [ 0.010023] pid_max: default: 32768 minimum: 301 [ 0.011162] LSM: Security Framework initializing [ 0.012084] Yama: becoming mindful. [ 0.013074] SELinux: Initializing. [ 0.014117] *** VALIDATE selinux *** [ 0.023340] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.028706] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.030183] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031150] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.032173] *** VALIDATE tmpfs *** [ 0.034032] *** VALIDATE proc *** [ 0.035304] *** VALIDATE cgroup *** [ 0.036017] *** VALIDATE cgroup2 *** [ 0.037314] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.038161] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.040006] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.041041] Spectre V2 : User space: Vulnerable [ 0.042017] Speculative Store Bypass: Vulnerable [ 0.045868] debug: unmapping init [mem 0xffffffff98259000-0xffffffff98260fff] [ 0.047955] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.048808] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.049034] ... version: 2 [ 0.050021] ... bit width: 48 [ 0.051016] ... generic registers: 4 [ 0.052015] ... value mask: 0000ffffffffffff [ 0.053022] ... max period: 00007fffffffffff [ 0.054022] ... fixed-purpose events: 3 [ 0.055017] ... event mask: 000000070000000f [ 0.056340] rcu: Hierarchical SRCU implementation. [ 0.058915] smp: Bringing up secondary CPUs ... [ 0.059709] x86: Booting SMP configuration: [ 0.060039] .... node #0, CPUs: #1 #2 #3 [ 0.064022] smp: Brought up 1 node, 4 CPUs [ 0.066031] smpboot: Max logical packages: 1 [ 0.067026] smpboot: Total of 4 processors activated (19200.00 BogoMIPS) [ 0.250583] node 0 deferred pages initialised in 180ms [ 0.255017] devtmpfs: initialized [ 0.256298] x86/mm: Memory block size: 128MB [ 0.258855] gcov: version magic: 0x41383552 [ 0.260422] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.264140] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.267462] pinctrl core: initialized pinctrl subsystem [ 0.270280] [ 0.270861] ************************************************************* [ 0.274020] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.278023] ** ** [ 0.281022] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.284019] ** ** [ 0.286017] ** This means that this kernel is built to expose internal ** [ 0.289021] ** IOMMU data structures, which may compromise security on ** [ 0.291019] ** your system. ** [ 0.294018] ** ** [ 0.296017] ** If you see this message and you are not debugging the ** [ 0.299018] ** kernel, report this immediately to your vendor! ** [ 0.302022] ** ** [ 0.305022] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.307017] ************************************************************* [ 0.309837] NET: Registered protocol family 16 [ 0.312631] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.315086] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.318089] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.322139] cpuidle: using governor menu [ 0.323759] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.326542] PCI: Using configuration type 1 for base access [ 0.329154] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.338059] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.341045] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.344215] cryptd: max_cpu_qlen set to 1000 [ 0.348275] ACPI: Added _OSI(Module Device) [ 0.350022] ACPI: Added _OSI(Processor Device) [ 0.351018] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.353021] ACPI: Added _OSI(Processor Aggregator Device) [ 0.357689] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.365180] ACPI: Interpreter enabled [ 0.366085] ACPI: PM: (supports S0 S3 S4 S5) [ 0.369019] ACPI: Using IOAPIC for interrupt routing [ 0.370135] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.374458] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.384832] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.387068] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.390030] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.394165] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.399701] acpiphp: Slot [2] registered [ 0.401194] acpiphp: Slot [5] registered [ 0.403232] acpiphp: Slot [6] registered [ 0.405317] acpiphp: Slot [3] registered [ 0.406219] acpiphp: Slot [4] registered [ 0.408275] acpiphp: Slot [7] registered [ 0.410145] acpiphp: Slot [8] registered [ 0.412159] acpiphp: Slot [9] registered [ 0.414147] acpiphp: Slot [10] registered [ 0.415194] acpiphp: Slot [11] registered [ 0.417142] acpiphp: Slot [12] registered [ 0.419164] acpiphp: Slot [13] registered [ 0.420163] acpiphp: Slot [14] registered [ 0.422171] acpiphp: Slot [15] registered [ 0.424177] acpiphp: Slot [16] registered [ 0.426130] acpiphp: Slot [17] registered [ 0.428151] acpiphp: Slot [18] registered [ 0.429132] acpiphp: Slot [19] registered [ 0.430116] acpiphp: Slot [20] registered [ 0.432197] acpiphp: Slot [21] registered [ 0.435173] acpiphp: Slot [22] registered [ 0.436101] acpiphp: Slot [23] registered [ 0.437127] acpiphp: Slot [24] registered [ 0.439163] acpiphp: Slot [25] registered [ 0.441142] acpiphp: Slot [26] registered [ 0.442189] acpiphp: Slot [27] registered [ 0.443140] acpiphp: Slot [28] registered [ 0.445181] acpiphp: Slot [29] registered [ 0.447147] acpiphp: Slot [30] registered [ 0.448178] acpiphp: Slot [31] registered [ 0.450116] PCI host bridge to bus 0000:00 [ 0.452030] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.454033] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.457038] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.459020] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.461035] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.464039] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.466247] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.469250] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.473528] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.482023] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.487675] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.490037] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.493030] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.495030] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.498732] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.501884] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.504050] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.506960] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.512020] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.522022] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.527035] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.533545] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.540020] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.555021] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.571023] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.585490] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.593022] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.601022] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.620025] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.629314] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.632514] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.636518] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.638419] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.641310] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.646150] iommu: Default domain type: Passthrough [ 0.648464] SCSI subsystem initialized [ 0.649258] ACPI: bus type USB registered [ 0.651153] usbcore: registered new interface driver usbfs [ 0.653110] usbcore: registered new interface driver hub [ 0.654091] usbcore: registered new device driver usb [ 0.656241] pps_core: LinuxPPS API ver. 1 registered [ 0.657011] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.659153] PTP clock support registered [ 0.661301] EDAC MC: Ver: 3.0.0 [ 0.663162] PCI: Using ACPI for IRQ routing [ 0.665074] NetLabel: Initializing [ 0.666011] NetLabel: domain hash size = 128 [ 0.667015] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.668147] NetLabel: unlabeled traffic allowed by default [ 0.670152] vgaarb: loaded [ 0.672403] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.674024] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.682096] clocksource: Switched to clocksource kvm-clock [ 0.799949] VFS: Disk quotas dquot_6.6.0 [ 0.801830] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.804670] *** VALIDATE ramfs *** [ 0.806182] *** VALIDATE hugetlbfs *** [ 0.807925] pnp: PnP ACPI init [ 0.810491] pnp: PnP ACPI: found 6 devices [ 0.830514] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.833753] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.836548] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.838953] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.841888] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.844796] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.848295] NET: Registered protocol family 2 [ 0.850621] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.855573] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.859235] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.865038] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.868809] TCP: Hash tables configured (established 65536 bind 65536) [ 0.872488] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.875813] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.878959] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.882499] NET: Registered protocol family 1 [ 0.885604] RPC: Registered named UNIX socket transport module. [ 0.888301] RPC: Registered udp transport module. [ 0.890339] RPC: Registered tcp transport module. [ 0.892212] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.895124] NET: Registered protocol family 44 [ 0.897541] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.900179] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.902620] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.905144] PCI: CLS 0 bytes, default 64 [ 0.907605] Unpacking initramfs... [ 2.366112] debug: unmapping init [mem 0xffff979f7cc64000-0xffff979f7ffcffff] [ 2.373030] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.375769] software IO TLB: mapped [mem 0x00000000b8c64000-0x00000000bcc64000] (64MB) [ 2.380574] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x22983777dd9, max_idle_ns: 440795300422 ns [ 2.907514] Initialise system trusted keyrings [ 2.909143] Key type blacklist registered [ 2.910888] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.920398] zbud: loaded [ 2.924143] *** VALIDATE nfs *** [ 2.925190] *** VALIDATE nfs4 *** [ 2.926525] pstore: using deflate compression [ 2.929244] Platform Keyring initialized [ 3.030422] NET: Registered protocol family 38 [ 3.032405] Key type asymmetric registered [ 3.035038] Asymmetric key parser 'x509' registered [ 3.037249] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.040626] io scheduler mq-deadline registered [ 3.042724] io scheduler kyber registered [ 3.044790] io scheduler bfq registered [ 3.046664] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.049799] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.053287] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.056377] ACPI: Power Button [PWRF] [ 3.065618] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.073035] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.085173] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.114808] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.143747] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.148756] Non-volatile memory driver v1.3 [ 3.150773] Linux agpgart interface v0.103 [ 3.188647] virtio_blk virtio1: [vda] 145920 512-byte logical blocks (74.7 MB/71.3 MiB) [ 3.191942] vda: detected capacity change from 0 to 74711040 [ 3.207490] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.210653] vdb: detected capacity change from 0 to 1073741824 [ 3.218105] libphy: Fixed MDIO Bus: probed [ 3.225411] usbcore: registered new interface driver usbserial_generic [ 3.228913] usbserial: USB Serial support registered for generic [ 3.231958] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.237730] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.240222] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.243319] mousedev: PS/2 mouse device common for all mice [ 3.247538] rtc_cmos 00:05: RTC can wake from S4 [ 3.250735] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.254443] rtc_cmos 00:05: registered as rtc0 [ 3.256894] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.261143] intel_pstate: CPU model not supported [ 3.263757] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.267718] hid: raw HID events driver (C) Jiri Kosina [ 3.267985] usbcore: registered new interface driver usbhid [ 3.272597] usbhid: USB HID core driver [ 3.274700] drop_monitor: Initializing network drop monitor service [ 3.274862] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.277345] Initializing XFRM netlink socket [ 3.282804] NET: Registered protocol family 10 [ 3.286605] Segment Routing with IPv6 [ 3.287898] NET: Registered protocol family 17 [ 3.290067] mpls_gso: MPLS GSO support [ 3.298409] RAS: Correctable Errors collector initialized. [ 3.302095] AVX version of gcm_enc/dec engaged. [ 3.304127] AES CTR mode by8 optimization enabled [ 3.385197] sched_clock: Marking stable (3385107793, 0)->(4384618623, -999510830) [ 3.389476] registered taskstats version 1 [ 3.391527] Loading compiled-in X.509 certificates [ 3.394153] zswap: loaded using pool lzo/zbud [ 3.423530] Key type big_key registered [ 3.438334] Key type encrypted registered [ 3.440171] ima: No TPM chip found, activating TPM-bypass! [ 3.442634] ima: Allocated hash algorithm: sha1 [ 3.445016] ima: No architecture policies found [ 3.446956] evm: Initialising EVM extended attributes: [ 3.448967] evm: security.selinux [ 3.450361] evm: security.ima [ 3.451572] evm: security.capability [ 3.453107] evm: HMAC attrs: 0x1 [ 3.455596] rtc_cmos 00:05: setting system clock to 2026-08-17 06:14:35 UTC (1786947275) [ 3.461677] debug: unmapping init [mem 0xffffffff99203000-0xffffffff993fffff] [ 3.465238] debug: unmapping init [mem 0xffffffff97f82000-0xffffffff98258fff] [ 3.474376] Write protecting the kernel read-only data: 28672k [ 3.477921] debug: unmapping init [mem 0xffffffff96603000-0xffffffff967fffff] [ 3.481297] debug: unmapping init [mem 0xffffffff96f14000-0xffffffff96ffffff] [ 3.519431] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 3.530621] systemd[1]: Detected virtualization kvm. [ 3.532695] systemd[1]: Detected architecture x86-64. [ 3.535152] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.562885] systemd[1]: No hostname configured. [ 3.565109] systemd[1]: Set hostname to . [ 3.567421] random: systemd: uninitialized urandom read (16 bytes read) [ 3.570518] systemd[1]: Initializing machine ID from random generator. [ 3.717915] random: systemd: uninitialized urandom read (16 bytes read) [ 3.721225] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 3.725969] random: systemd: uninitialized urandom read (16 bytes read) [ 3.728061] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 3.732521] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ OK ] Listening on udev Kernel Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Reached target Timers. [ OK ] Listening on Journal Socket. Starting Create Volatile Files and Directories... Starting Create list of required st…ce nodes for the current kernel... Starting Journal Service... [ OK ] Started Memstrack Anylazing Service. Starting Setup Virtual Console... [ OK ] Reached target Slices. [ OK ] Reached target Sockets. Starting Apply Kernel Variables... [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Swap. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. Starting Create Static Device Nodes in /dev... [ OK ] Started Setup Virtual Console. Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.412436] device-mapper: uevent: version 1.0.3 [ 4.414968] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 5.181176] virtio_net virtio0 ens2: renamed from eth0 [ 5.241849] scsi host0: ata_piix [ 5.250305] scsi host1: ata_piix [ 5.252208] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.254656] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.715920] dracut-initqueue[583]: RTNETLINK answers: File exists [ 9.961550] random: crng init done [ 9.964303] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 10.478424] 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 System Initialization. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Swap. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Paths. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Sockets. [ 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... [ 11.712187] printk: systemd: 26 output lines suppressed due to ratelimiting [ 12.014844] SELinux: Disabled at runtime. [ 12.081854] 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) [ 12.091819] systemd[1]: Detected virtualization kvm. [ 12.094043] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.664924] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.668379] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.673857] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.679710] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.682270] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.690100] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.694524] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Listening on udev Kernel Socket. Activating swap /dev/disk/by-label/SWAP... Mounting Huge Pages File System... Starting Remount Root and Kernel File Systems... [ 12.743271] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Listening on Process Core Dump Socket. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on RPCbind Server Activation Socket. Starting udev Coldplug all Devices... [ OK ] Reached target rpc_pipefs.target. Mounting POSIX Message Queue File System... [ OK ] Started Forward Password Requests to Wall Directory Watch. Mounting Kernel Debug File System... [ OK ] Reached target RPC Port Mapper. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. Starting Apply Kernel Variables... [ OK ] Created slice system-getty.slice. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Stopped target Initrd Root File System. [ OK ] Started Journal Service. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Huge Pages File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... Starting udev Kernel Device Manager... [ 13.162123] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Mounted /mnt. [ OK ] Started udev Kernel Device Manager. [ 13.502493] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.522898] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.609097] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.625797] EDAC sbridge: Ver: 1.1.2 [ 14.796816] Key type dns_resolver registered [ 15.113819] NFS: Registering the id_resolver key type [ 15.115700] Key type id_resolver registered [ 15.117362] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started dnf makecache --timer. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... Starting Restore /run/initramfs on shutdown... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started irqbalance daemon. Starting Login Service... [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... [ OK ] Started OpenSSH server daemon. Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg405-client login: [ 50.961398] libcfs: loading out-of-tree module taints kernel. [ 51.160678] Key type ._llcrypt registered [ 51.170334] Key type .llcrypt registered [ 52.039337] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 52.065505] alg: No test for adler32 (adler32-zlib) [ 53.826872] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [ 55.035729] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [ 56.839196] Key type lgssc registered [ 59.128833] Lustre: Echo OBD driver; http://www.lustre.org/ [ 97.423626] hrtimer: interrupt took 14599934 ns [ 294.954872] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 301.681258] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 318.978321] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing check_logdir /tmp/testlogs/ [ 320.481526] Lustre: lustre-OST0000-osc-ffff979fc934f800: disconnect after 22s idle [ 327.024345] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing yml_node [ 333.350610] Lustre: DEBUG MARKER: Client: 2.17.57.2 [ 337.426863] Lustre: DEBUG MARKER: MDS: 2.17.57.2 [ 340.849897] Lustre: DEBUG MARKER: OSS: 2.17.57.2 [ 343.161485] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Mon Aug 17 02:20:13 EDT 2026 [ 364.915981] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 366.625293] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 370.421540] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 380.825180] Lustre: Unmounted lustre-client [ 528.026402] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 535.441961] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 565.546458] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 609.841699] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 02:24:39 (1786947879) [ 613.270753] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 615.474102] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 02:24:45 (1786947885) [ 658.768866] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 665.867320] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 692.643197] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 693.412729] LustreError: 2390:0:(lmv_obd.c:211:lmv_notify()) activation of lustre-MDT0001_UUID failed: -22 [ 698.962791] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 705.098072] Lustre: Unmounted lustre-client [ 745.622756] Key type lgssc unregistered [ 745.970793] LNet: 15061:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 745.980688] LNetError: 15061:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 747.048217] LNet: Removed LNI 192.168.204.5@tcp [ 748.161192] Key type .llcrypt unregistered [ 748.165630] Key type ._llcrypt unregistered [ 768.302249] Key type ._llcrypt registered [ 768.308657] Key type .llcrypt registered [ 770.001783] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 02:27:20 (1786948040) [ 772.037249] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 772.070268] alg: No test for adler32 (adler32-zlib) [ 773.288951] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [ 773.598551] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [ 775.340041] Key type lgssc registered [ 776.976241] Lustre: Echo OBD driver; http://www.lustre.org/ [ 863.107624] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 953.336756] Lustre: Unmounted lustre-client [ 985.981033] Key type lgssc unregistered [ 986.342170] LNet: 18849:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 986.354972] LNetError: 18849:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 986.388230] LNet: Removed LNI 192.168.204.5@tcp [ 987.289921] Key type .llcrypt unregistered [ 987.292179] Key type ._llcrypt unregistered [ 997.908597] Key type ._llcrypt registered [ 997.910296] Key type .llcrypt registered [ 999.798630] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 02:31:09 (1786948269) [ 1002.053499] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1002.109748] alg: No test for adler32 (adler32-zlib) [ 1003.462995] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [ 1003.822682] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [ 1005.592842] Key type lgssc registered [ 1006.816770] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1062.105708] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1069.571253] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1097.133658] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1103.927154] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 1115.007466] Lustre: Unmounted lustre-client [ 1144.781859] Key type lgssc unregistered [ 1145.106255] LNet: 22458:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1145.130720] LNetError: 22458:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1146.220733] LNet: Removed LNI 192.168.204.5@tcp [ 1147.675625] Key type .llcrypt unregistered [ 1147.682141] Key type ._llcrypt unregistered [ 1163.282962] Key type ._llcrypt registered [ 1163.289757] Key type .llcrypt registered [ 1166.042910] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 02:33:54 (1786948434) [ 1168.616666] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1168.660481] alg: No test for adler32 (adler32-zlib) [ 1169.820544] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [ 1170.167950] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [ 1171.882051] Key type lgssc registered [ 1173.702454] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1229.709694] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1235.940268] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1257.864732] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1260.968989] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 1270.719405] Lustre: Unmounted lustre-client [ 1302.251584] Key type lgssc unregistered [ 1302.554506] LNet: 25653:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1302.564236] LNetError: 25653:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1303.591670] LNet: Removed LNI 192.168.204.5@tcp [ 1304.599786] Key type .llcrypt unregistered [ 1304.601532] Key type ._llcrypt unregistered [ 1322.230618] Key type ._llcrypt registered [ 1322.233284] Key type .llcrypt registered [ 1323.689289] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 02:36:34 (1786948594) [ 1326.939107] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1326.979076] alg: No test for adler32 (adler32-zlib) [ 1328.189582] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [ 1328.537261] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [ 1330.224561] Key type lgssc registered [ 1331.649450] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1389.508382] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1398.175706] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1424.361585] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1431.652412] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 1436.669180] Lustre: lustre-MDT0000-mdc-ffff979fc43f0000: Connection to lustre-MDT0000 (at 192.168.204.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1446.914261] Lustre: lustre-MDT0001-mdc-ffff979fc43f0000: Connection to lustre-MDT0001 (at 192.168.204.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1452.032899] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1457.120457] Lustre: 26613:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786948713/real 1786948713] req@00000000fbcbd572 x1873751415199488/t0(0) o400->MGC192.168.204.105@tcp@192.168.204.105@tcp:26/25 lens 224/224 e 0 to 1 dl 1786948729 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1457.150044] LustreError: MGC192.168.204.105@tcp: Connection to MGS (at 192.168.204.105@tcp) was lost; in progress operations using this service will fail [ 1462.420481] Lustre: Unmounted lustre-client [ 1483.990229] Key type lgssc unregistered [ 1484.335372] LNet: 29312:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1484.350497] LNetError: 29312:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1484.383642] LNet: Removed LNI 192.168.204.5@tcp [ 1485.533875] Key type .llcrypt unregistered [ 1485.541727] Key type ._llcrypt unregistered [ 1502.999243] Key type ._llcrypt registered [ 1503.002670] Key type .llcrypt registered [ 1504.345377] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 02:39:34 (1786948774) [ 1507.669789] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1507.752646] alg: No test for adler32 (adler32-zlib) [ 1508.969264] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [ 1509.332703] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [ 1511.015283] Key type lgssc registered [ 1512.702534] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1571.519896] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1578.587801] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1602.593909] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1613.927100] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 1624.053747] Lustre: lustre-OST0000-osc-ffff979fd1000800: Connection to lustre-OST0000 (at 192.168.204.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1627.190697] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1637.554097] Lustre: Unmounted lustre-client [ 1665.813342] Key type lgssc unregistered [ 1666.123533] LNet: 32930:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1666.131089] LNetError: 32930:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1666.157421] LNet: Removed LNI 192.168.204.5@tcp [ 1667.007130] Key type .llcrypt unregistered [ 1667.018249] Key type ._llcrypt unregistered [ 1685.022935] Key type ._llcrypt registered [ 1685.026905] Key type .llcrypt registered [ 1687.057598] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 02:42:36 (1786948956) [ 1691.316164] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1691.373225] alg: No test for adler32 (adler32-zlib) [ 1692.667022] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [ 1693.004741] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [ 1694.767614] Key type lgssc registered [ 1696.513122] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1758.292594] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1764.221358] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1787.649323] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1798.720743] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 1803.474263] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1808.995132] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1821.404637] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-08-17 2h44m51s [ 1824.044589] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1829.350650] Lustre: lustre-MDT0000-mdc-ffff979fc9348800: Connection to lustre-MDT0000 (at 192.168.204.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1835.360376] LustreError: 36722:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff979fc9348800: can't stat MDS #0: rc = -19 [ 1837.465916] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1847.781434] LustreError: MGC192.168.204.105@tcp: Connection to MGS (at 192.168.204.105@tcp) was lost; in progress operations using this service will fail [ 1847.808181] Lustre: Evicted from MGS (at 192.168.204.105@tcp) after server handle changed from 0x373deb1b96a1be9f to 0x373deb1b96a1c249 [ 1847.825563] Lustre: MGC192.168.204.105@tcp: Connection restored to 192.168.204.105@tcp (at 192.168.204.105@tcp) [ 1848.879258] LustreError: lustre-MDT0000-mdc-ffff979fc9348800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1848.905441] Lustre: lustre-MDT0000-mdc-ffff979fc9348800: Connection restored to 192.168.204.105@tcp (at 192.168.204.105@tcp) [ 1855.703534] Lustre: DEBUG MARKER: Wait for df (36722) ... [ 1857.876774] Lustre: DEBUG MARKER: done [ 1861.139451] Lustre: Unmounted lustre-client [ 1892.980748] Key type lgssc unregistered [ 1893.354164] LNet: 37741:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1893.363906] LNetError: 37741:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1893.388487] LNet: Removed LNI 192.168.204.5@tcp [ 1894.244594] Key type .llcrypt unregistered [ 1894.248754] Key type ._llcrypt unregistered [ 1932.453862] Key type ._llcrypt registered [ 1932.458051] Key type .llcrypt registered [ 1934.166388] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 02:46:44 (1786949204) [ 1937.312420] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1937.459527] alg: No test for adler32 (adler32-zlib) [ 1938.680801] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [ 1939.008590] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [ 1940.729598] Key type lgssc registered [ 1942.522476] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2001.824360] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2009.124340] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2035.054667] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2036.883186] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 2042.096414] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-08-17 2h48m32s [ 2044.002481] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 2053.048593] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 2056.457170] Lustre: DEBUG MARKER: Creating a test file and stat it [ 2076.641076] Lustre: 41886:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786949328/real 1786949328] req@00000000e6be1078 x1873752055880192/t0(0) o101->lustre-MDT0000-mdc-ffff979fc404b000@192.168.204.105@tcp:12/10 lens 576/1152 e 0 to 1 dl 1786949348 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 2076.676066] Lustre: lustre-MDT0000-mdc-ffff979fc404b000: Connection to lustre-MDT0000 (at 192.168.204.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2076.722459] Lustre: lustre-MDT0000-mdc-ffff979fc404b000: Connection restored to 192.168.204.105@tcp (at 192.168.204.105@tcp) [ 2098.696744] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 2102.575829] Lustre: DEBUG MARKER: done [ 2108.267093] Lustre: Unmounted lustre-client [ 2138.759710] Key type lgssc unregistered [ 2139.206777] LNet: 42776:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2139.224722] LNetError: 42776:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2140.277847] LNet: Removed LNI 192.168.204.5@tcp [ 2141.325915] Key type .llcrypt unregistered [ 2141.332182] Key type ._llcrypt unregistered [ 2178.514909] Key type ._llcrypt registered [ 2178.522525] Key type .llcrypt registered [ 2180.250721] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 02:50:50 (1786949450) [ 2181.899361] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 2184.069787] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 02:50:54 (1786949454) [ 2188.262828] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2188.312941] alg: No test for adler32 (adler32-zlib) [ 2189.396175] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [ 2189.729516] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [ 2191.511278] Key type lgssc registered [ 2192.863526] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2225.501205] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 02:51:35 (1786949495) [ 2260.039055] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2266.977743] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2289.267347] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2292.231709] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 2295.759483] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 2300.751953] Lustre: Unmounted lustre-client [ 2319.002141] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 2371.964725] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2379.645527] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2380.639747] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 2386.020743] Lustre: Unmounted lustre-client [ 2463.142339] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2469.803433] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2470.881882] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 2475.827716] Lustre: Unmounted lustre-client [ 2499.883880] Lustre: DEBUG MARKER: files compared the same [ 2527.132593] Key type lgssc unregistered [ 2527.466249] LNet: 50408:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2527.478536] LNetError: 50408:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2527.506335] LNet: Removed LNI 192.168.204.5@tcp [ 2528.496657] Key type .llcrypt unregistered [ 2528.508423] Key type ._llcrypt unregistered [ 2546.176437] Key type ._llcrypt registered [ 2546.179318] Key type .llcrypt registered [ 2547.957433] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 02:56:57 (1786949817) [ 2550.872689] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2550.885934] alg: No test for adler32 (adler32-zlib) [ 2551.969988] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [ 2552.293796] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [ 2553.975169] Key type lgssc registered [ 2555.414644] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2610.159167] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2615.525922] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2639.622913] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2642.299514] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 2649.669856] Lustre: Unmounted lustre-client [ 2686.857547] Key type lgssc unregistered [ 2687.174878] LNet: 53994:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2687.196983] LNetError: 53994:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2687.223270] LNet: Removed LNI 192.168.204.5@tcp [ 2687.925155] Key type .llcrypt unregistered [ 2687.928866] Key type ._llcrypt unregistered [ 2704.213576] Key type ._llcrypt registered [ 2704.215195] Key type .llcrypt registered [ 2705.666232] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 02:59:36 (1786949976) [ 2708.094198] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2708.104227] alg: No test for adler32 (adler32-zlib) [ 2709.308135] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [ 2709.589788] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [ 2711.367206] Key type lgssc registered [ 2712.977986] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2806.355206] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2843.612994] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2851.678194] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2879.846359] Key type lgssc unregistered [ 2880.164824] LNet: 57499:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2880.171761] LNetError: 57499:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2880.192566] LNet: Removed LNI 192.168.204.5@tcp [ 2881.140397] Key type .llcrypt unregistered [ 2881.142100] Key type ._llcrypt unregistered [ 2899.349554] Key type ._llcrypt registered [ 2899.351727] Key type .llcrypt registered [ 2900.891366] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 03:02:51 (1786950171) [ 2904.861316] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2904.929308] alg: No test for adler32 (adler32-zlib) [ 2906.234353] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [ 2906.649425] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [ 2908.343208] Key type lgssc registered [ 2909.512068] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2994.671679] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 3001.856651] Lustre: Unmounted lustre-client [ 3042.245309] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 03:05:12 (1786950312) [ 3186.106674] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3203.863334] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 3212.224817] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3212.255111] LustreError: 63973:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff979fc43f3000: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 3212.313573] Lustre: 58484:0:(llite_lib.c:4352:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.204.105@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 3222.630704] Lustre: Unmounted lustre-client [ 3283.131272] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 03:09:13 (1786950553) [ 3297.603260] Key type lgssc unregistered [ 3298.066123] LNet: 65190:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3298.101795] LNetError: 65190:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3298.142280] LNet: Removed LNI 192.168.204.5@tcp [ 3299.254898] Key type .llcrypt unregistered [ 3299.259519] Key type ._llcrypt unregistered [ 3316.291581] Key type ._llcrypt registered [ 3316.297093] Key type .llcrypt registered [ 3316.638565] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3316.657452] alg: No test for adler32 (adler32-zlib) [ 3317.904849] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [ 3318.207528] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [ 3319.983721] Key type lgssc registered [ 3321.645394] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3381.128316] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3387.230886] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3409.783472] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3412.862257] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 3421.217371] Lustre: Unmounted lustre-client [ 3450.728811] Key type lgssc unregistered [ 3450.990064] LNet: 68206:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3450.996843] LNetError: 68206:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3451.010681] LNet: Removed LNI 192.168.204.5@tcp [ 3451.836271] Key type .llcrypt unregistered [ 3451.839533] Key type ._llcrypt unregistered [ 3463.286273] Key type ._llcrypt registered [ 3463.290377] Key type .llcrypt registered [ 3463.833859] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3463.897217] alg: No test for adler32 (adler32-zlib) [ 3465.000072] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [ 3465.237584] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [ 3466.911304] Key type lgssc registered [ 3468.528673] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3590.090179] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3598.461422] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3622.661194] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3629.623616] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 3633.217698] Lustre: Unmounted lustre-client [ 3660.439460] Key type lgssc unregistered [ 3660.680027] LNet: 71642:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3660.688728] LNetError: 71642:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3660.705047] LNet: Removed LNI 192.168.204.5@tcp [ 3661.578409] Key type .llcrypt unregistered [ 3661.581055] Key type ._llcrypt unregistered [ 3680.594317] Key type ._llcrypt registered [ 3680.597529] Key type .llcrypt registered [ 3682.650907] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 03:15:52 (1786950952) [ 3685.455803] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3685.647102] alg: No test for adler32 (adler32-zlib) [ 3686.837537] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [ 3687.163873] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [ 3688.887974] Key type lgssc registered [ 3690.268579] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3748.000595] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3756.062289] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3785.056061] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3791.424517] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 3797.023141] Lustre: Unmounted lustre-client [ 3797.539300] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 3800.788542] Lustre: Unmounted lustre-client [ 3843.389813] Key type lgssc unregistered [ 3843.841467] LNet: 75341:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3843.846329] LNetError: 75341:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3843.865819] LNet: Removed LNI 192.168.204.5@tcp [ 3844.530978] Key type .llcrypt unregistered [ 3844.540327] Key type ._llcrypt unregistered [ 3860.364436] Key type ._llcrypt registered [ 3860.371186] Key type .llcrypt registered [ 3860.746867] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3860.768228] alg: No test for adler32 (adler32-zlib) [ 3861.807831] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [ 3862.086899] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [ 3863.727253] Key type lgssc registered [ 3865.661784] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3927.872260] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3934.109260] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3955.444726] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3959.133510] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 3967.768725] Lustre: Unmounted lustre-client [ 4001.222788] Key type lgssc unregistered [ 4001.584556] LNet: 78380:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4001.606884] LNetError: 78380:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4001.648533] LNet: Removed LNI 192.168.204.5@tcp [ 4002.964660] Key type .llcrypt unregistered [ 4002.967319] Key type ._llcrypt unregistered [ 4020.973568] Key type ._llcrypt registered [ 4020.976180] Key type .llcrypt registered [ 4022.553152] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 03:21:32 (1786951292) [ 4024.978147] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4025.004267] alg: No test for adler32 (adler32-zlib) [ 4026.134322] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [ 4026.434063] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [ 4028.191212] Key type lgssc registered [ 4029.364023] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4083.886824] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4092.441353] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4116.215441] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4128.355314] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 4143.284969] Lustre: lustre: root_squash is set to 0:0 [ 4143.349118] Lustre: lustre: nosquash_nids is cleared [ 4156.477070] Lustre: lustre: root_squash is set to 500:500 [ 4168.757651] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.204.5@tcp 192.168.0.[2,10]@tcp [ 4177.296656] Lustre: lustre: nosquash_nids set to 192.168.204.5/24@tcp [ 4180.559726] Lustre: Unmounted lustre-client [ 4209.466721] Key type lgssc unregistered [ 4209.836836] LNet: 82342:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4209.842814] LNetError: 82342:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4209.860660] LNet: Removed LNI 192.168.204.5@tcp [ 4211.011605] Key type .llcrypt unregistered [ 4211.016143] Key type ._llcrypt unregistered [ 4230.317658] Key type ._llcrypt registered [ 4230.321953] Key type .llcrypt registered [ 4232.155912] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 03:25:02 (1786951502) [ 4235.428239] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 4237.512622] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 03:25:07 (1786951507) [ 4239.747299] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4239.897586] alg: No test for adler32 (adler32-zlib) [ 4241.136486] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [ 4241.405088] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [ 4243.048110] Key type lgssc registered [ 4244.730495] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4305.769384] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4313.471929] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4340.307557] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4340.925770] Lustre: lustre: root_squash is set to 500:500 [ 4340.944559] Lustre: lustre: nosquash_nids set to 192.168.204.5/24@tcp [ 4346.455798] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 4352.881221] Lustre: Unmounted lustre-client [ 4382.984585] Key type lgssc unregistered [ 4383.364784] LNet: 86311:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4383.368817] LNetError: 86311:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4383.400299] LNet: Removed LNI 192.168.204.5@tcp [ 4384.217233] Key type .llcrypt unregistered [ 4384.219090] Key type ._llcrypt unregistered [ 4400.735926] Key type ._llcrypt registered [ 4400.737449] Key type .llcrypt registered [ 4402.653930] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 4405.159574] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 03:27:55 (1786951675) [ 4427.595579] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4427.612086] alg: No test for adler32 (adler32-zlib) [ 4428.798118] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [ 4429.144545] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [ 4430.905287] Key type lgssc registered [ 4432.240602] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4529.204950] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4537.105766] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4563.797448] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4653.072092] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4661.856910] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4695.958332] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4710.340220] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 4749.737636] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff979fd0172800.ost_server_uuid 50 [ 4751.420412] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff979fd0172800.ost_server_uuid in IDLE state after 0 sec [ 4752.144942] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 4757.142831] Lustre: Unmounted lustre-client [ 4759.960778] Lustre: Unmounted lustre-client [ 4815.138119] Key type lgssc unregistered [ 4815.515438] LNet: 93991:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4815.530688] LNetError: 93991:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4815.558175] LNet: Removed LNI 192.168.204.5@tcp [ 4816.763118] Key type .llcrypt unregistered [ 4816.769223] Key type ._llcrypt unregistered [ 4855.758190] Key type ._llcrypt registered [ 4855.761303] Key type .llcrypt registered [ 4857.215677] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 03:35:27 (1786952127) [ 4879.489752] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4879.573712] alg: No test for adler32 (adler32-zlib) [ 4880.837624] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [ 4881.330139] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [ 4883.063477] Key type lgssc registered [ 4884.673670] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4977.953166] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4984.704924] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5014.246222] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5017.013263] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 5038.625517] Lustre: 95356:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786952294/real 1786952294] req@00000000fbc8b02c x1873755140789504/t0(0) o400->lustre-OST0000-osc-ffff979fda7b7000@192.168.204.105@tcp:28/4 lens 224/224 e 0 to 1 dl 1786952310 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 5038.657342] Lustre: lustre-OST0000-osc-ffff979fda7b7000: Connection to lustre-OST0000 (at 192.168.204.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5043.295451] Lustre: 95356:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786952299/real 1786952299] req@000000007142e6d9 x1873755140790016/t0(0) o400->lustre-OST0000-osc-ffff979fda7b7000@192.168.204.105@tcp:28/4 lens 224/224 e 0 to 1 dl 1786952315 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 5048.408335] Lustre: lustre-OST0000-osc-ffff979fda7b7000: Connection restored to 192.168.204.105@tcp (at 192.168.204.105@tcp) [ 5049.441756] Lustre: 95355:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786952304/real 1786952304] req@0000000003f33799 x1873755140790528/t0(0) o400->lustre-OST0000-osc-ffff979fda7b7000@192.168.204.105@tcp:28/4 lens 224/224 e 0 to 1 dl 1786952320 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 5053.407153] Lustre: 95356:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786952309/real 1786952309] req@0000000009cce9a0 x1873755140791040/t0(0) o400->lustre-OST0000-osc-ffff979fda7b7000@192.168.204.105@tcp:28/4 lens 224/224 e 0 to 1 dl 1786952325 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 5058.540980] Lustre: lustre-MDT0000-mdc-ffff979fda7b7000: Connection to lustre-MDT0000 (at 192.168.204.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5074.783574] Lustre: 95356:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786952330/real 1786952330] req@00000000f723a6c1 x1873755140793856/t0(0) o400->MGC192.168.204.105@tcp@192.168.204.105@tcp:26/25 lens 224/224 e 0 to 1 dl 1786952346 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 5074.822481] LustreError: MGC192.168.204.105@tcp: Connection to MGS (at 192.168.204.105@tcp) was lost; in progress operations using this service will fail [ 5084.146724] Lustre: Evicted from MGS (at 192.168.204.105@tcp) after server handle changed from 0x6fb2eced21c68f68 to 0x6fb2eced21c691fa [ 5084.165731] Lustre: MGC192.168.204.105@tcp: Connection restored to 192.168.204.105@tcp (at 192.168.204.105@tcp) [ 5089.244993] Lustre: lustre-MDT0000-mdc-ffff979fda7b7000: Connection restored to 192.168.204.105@tcp (at 192.168.204.105@tcp) [ 5095.234721] Lustre: Unmounted lustre-client [ 5133.048829] Key type lgssc unregistered [ 5133.356592] LNet: 99672:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5133.364811] LNetError: 99672:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5133.398480] LNet: Removed LNI 192.168.204.5@tcp [ 5134.452745] Key type .llcrypt unregistered [ 5134.460405] Key type ._llcrypt unregistered [ 5156.020945] Key type ._llcrypt registered [ 5156.023264] Key type .llcrypt registered [ 5158.251955] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 03:40:27 (1786952427) [ 5175.127461] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5175.315180] alg: No test for adler32 (adler32-zlib) [ 5176.690086] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [ 5177.096591] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [ 5178.760435] Key type lgssc registered [ 5180.951538] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5226.511786] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5331.899631] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5338.116983] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5362.570842] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5365.349162] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 5367.494836] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 5369.451698] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 03:44:00 (1786952640) [ 5640.950030] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5651.321618] Lustre: Unmounted lustre-client [ 5778.390638] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5786.170945] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5812.383291] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5868.113612] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 03:52:18 (1786953138) [ 5976.445332] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5982.635671] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6013.756214] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6014.682972] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 6021.357659] Lustre: Unmounted lustre-client [ 6056.917747] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 03:55:27 (1786953327) [ 6174.311075] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6182.282567] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6211.307192] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6212.345591] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 6218.705215] Lustre: Unmounted lustre-client [ 6249.830360] Key type lgssc unregistered [ 6250.163060] LNet: 122708:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6250.176410] LNetError: 122708:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6250.198180] LNet: Removed LNI 192.168.204.5@tcp [ 6251.151166] Key type .llcrypt unregistered [ 6251.156957] Key type ._llcrypt unregistered [ 6271.924650] Key type ._llcrypt registered [ 6271.927110] Key type .llcrypt registered [ 6273.196442] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 03:59:03 (1786953543) [ 6275.556025] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6275.606770] alg: No test for adler32 (adler32-zlib) [ 6276.768539] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [ 6277.115049] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [ 6278.889330] Key type lgssc registered [ 6280.421831] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6345.061799] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6351.391897] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6378.019235] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6385.212977] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 6398.503970] Lustre: Unmounted lustre-client [ 6437.147379] Key type lgssc unregistered [ 6437.367944] LNet: 126326:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6437.379561] LNetError: 126326:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6437.392802] LNet: Removed LNI 192.168.204.5@tcp [ 6438.143286] Key type .llcrypt unregistered [ 6438.149220] Key type ._llcrypt unregistered [ 6456.693485] Key type ._llcrypt registered [ 6456.697501] Key type .llcrypt registered [ 6458.438706] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 04:02:08 (1786953728) [ 6461.751470] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6461.774996] alg: No test for adler32 (adler32-zlib) [ 6462.815026] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [ 6463.096169] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [ 6464.815801] Key type lgssc registered [ 6466.195606] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6533.113722] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6540.914761] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6572.472705] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6575.328224] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 6580.724161] Lustre: lustre-OST0000-osc-ffff979fc484b800: Connection to lustre-OST0000 (at 192.168.204.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6595.373966] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff979fc484b800.ost_server_uuid 50 [ 6597.562084] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 6609.197958] Lustre: Unmounted lustre-client [ 6631.933383] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 04:05:01 (1786953901) [ 6672.236936] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6679.296582] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6708.604630] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6739.998881] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6741.064957] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 6746.094675] Lustre: lustre-OST0000-osc-ffff979fc47fa800: Connection to lustre-OST0000 (at 192.168.204.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6778.569198] Lustre: Unmounted lustre-client [ 6842.660069] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 04:08:32 (1786954112) [ 6888.245537] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6895.829911] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6921.524412] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6949.980583] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6950.758852] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 6956.026695] Lustre: lustre-OST0000-osc-ffff979fc47ff800: Connection to lustre-OST0000 (at 192.168.204.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6974.080158] Lustre: Unmounted lustre-client [ 7037.026914] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 04:11:47 (1786954307) [ 7158.264859] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7165.099631] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7193.288214] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7273.214242] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7279.876912] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7306.603579] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7341.950823] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 7387.367882] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7388.943124] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7391.905034] Lustre: Unmounted lustre-client [ 7438.101653] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 04:18:28 (1786954708) [ 7475.384826] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7482.316970] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7506.418848] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7545.434414] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7580.347980] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 7626.957923] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7628.694922] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7642.034189] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7652.432793] Lustre: Unmounted lustre-client [ 7721.326631] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 04:23:11 (1786954991) [ 7757.386339] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7763.960090] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7787.377617] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7788.012324] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 7813.508359] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7815.398551] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7833.893912] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff979fc8f97000.ost_server_uuid 50 [ 7836.551214] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff979fc8f97000.ost_server_uuid in IDLE state after 0 sec [ 7841.631173] Lustre: Unmounted lustre-client [ 7841.957739] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 7841.964566] Lustre: Skipped 3 previous similar messages [ 7842.129515] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 7843.591539] LustreError: 149772:0:(sec.c:403:import_sec_validate_get()) import 0000000061a91ee5 (NEW) with no sec [ 7908.948698] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 04:26:19 (1786955179) [ 7952.861284] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7960.100747] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7984.252331] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8008.639427] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8009.527389] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 8009.745397] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 8026.129830] Lustre: Unmounted lustre-client [ 8026.140520] Lustre: Skipped 1 previous similar message [ 8078.880695] Key type lgssc unregistered [ 8079.253442] LNet: 153201:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8079.272608] LNetError: 153201:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8079.297589] LNet: Removed LNI 192.168.204.5@tcp [ 8080.276817] Key type .llcrypt unregistered [ 8080.279954] Key type ._llcrypt unregistered [ 8099.763910] Key type ._llcrypt registered [ 8099.766646] Key type .llcrypt registered [ 8101.226278] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 04:29:31 (1786955371) [ 8102.175628] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8102.196098] alg: No test for adler32 (adler32-zlib) [ 8103.379669] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [ 8103.662530] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [ 8105.367984] Key type lgssc registered [ 8106.715315] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8173.844828] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8182.146978] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8207.299680] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8234.350412] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8235.856158] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 8237.047093] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 8242.455865] LustreError: lustre-MDT0001-mdc-ffff979fd13b1000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 8242.495581] Lustre: lustre-MDT0001-mdc-ffff979fd13b1000: Connection restored to 192.168.204.105@tcp (at 192.168.204.105@tcp) [ 8250.555509] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8252.617768] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 8277.031785] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 8282.114176] Lustre: Unmounted lustre-client [ 8315.978528] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 04:33:06 (1786955586) [ 8421.896486] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8430.201702] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8453.041460] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8453.735137] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 8486.316736] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8488.262835] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 8502.144787] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 8512.522038] Lustre: Unmounted lustre-client [ 8559.064724] Key type lgssc unregistered [ 8559.408221] LNet: 162824:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8559.419756] LNetError: 162824:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8560.495236] LNet: Removed LNI 192.168.204.5@tcp [ 8561.406369] Key type .llcrypt unregistered [ 8561.409645] Key type ._llcrypt unregistered [ 8598.429615] Key type ._llcrypt registered [ 8598.431959] Key type .llcrypt registered [ 8599.876521] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 04:37:50 (1786955870) [ 8602.509062] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8602.601919] alg: No test for adler32 (adler32-zlib) [ 8603.725760] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [ 8603.951725] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [ 8605.655216] Key type lgssc registered [ 8606.934784] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8668.460620] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8675.190849] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8703.070724] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8706.017879] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 8757.235808] Lustre: Unmounted lustre-client [ 8801.356373] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8802.134939] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 8839.434215] Lustre: Unmounted lustre-client [ 8871.971905] Key type lgssc unregistered [ 8872.256761] LNet: 167285:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8872.268292] LNetError: 167285:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8873.317733] LNet: Removed LNI 192.168.204.5@tcp [ 8874.339540] Key type .llcrypt unregistered [ 8874.342389] Key type ._llcrypt unregistered [ 8895.793601] Key type ._llcrypt registered [ 8895.801087] Key type .llcrypt registered [ 8897.778985] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 04:42:47 (1786956167) [ 8902.036428] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8902.140600] alg: No test for adler32 (adler32-zlib) [ 8903.121163] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [ 8903.313855] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [ 8904.975395] Key type lgssc registered [ 8907.070989] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8971.059373] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8978.663310] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9003.767709] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9009.722332] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 9029.391179] Lustre: Unmounted lustre-client [ 9057.394377] Key type lgssc unregistered [ 9057.600184] LNet: 171051:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9057.611803] LNetError: 171051:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9057.629631] LNet: Removed LNI 192.168.204.5@tcp [ 9058.284624] Key type .llcrypt unregistered [ 9058.287118] Key type ._llcrypt unregistered [ 9074.249172] Key type ._llcrypt registered [ 9074.289758] Key type .llcrypt registered [ 9074.932730] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9074.990895] alg: No test for adler32 (adler32-zlib) [ 9075.972302] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [ 9076.252252] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [ 9077.935886] Key type lgssc registered [ 9079.039939] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9129.145924] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9134.836444] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9156.403972] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9158.320180] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 9166.370880] Lustre: Unmounted lustre-client [ 9194.602083] Key type lgssc unregistered [ 9194.825953] LNet: 174119:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9194.837671] LNetError: 174119:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9194.855277] LNet: Removed LNI 192.168.204.5@tcp [ 9195.558205] Key type .llcrypt unregistered [ 9195.560268] Key type ._llcrypt unregistered [ 9211.322372] Key type ._llcrypt registered [ 9211.325909] Key type .llcrypt registered [ 9212.676285] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 04:48:03 (1786956483) [ 9215.100479] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9215.110945] alg: No test for adler32 (adler32-zlib) [ 9216.176308] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [ 9216.397118] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [ 9218.055248] Key type lgssc registered [ 9219.309231] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9271.233243] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9278.012796] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9301.310994] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9303.105637] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 9321.835398] Lustre: Unmounted lustre-client [ 9351.144201] Key type lgssc unregistered [ 9351.382765] LNet: 177879:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9351.399157] LNetError: 177879:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9351.417630] LNet: Removed LNI 192.168.204.5@tcp [ 9352.232506] Key type .llcrypt unregistered [ 9352.240280] Key type ._llcrypt unregistered [ 9366.254320] Key type ._llcrypt registered [ 9366.259563] Key type .llcrypt registered [ 9366.914533] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9366.949427] alg: No test for adler32 (adler32-zlib) [ 9368.058770] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [ 9368.339137] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [ 9370.031228] Key type lgssc registered [ 9371.156850] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9423.790387] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9429.281533] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9451.139084] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9452.948659] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 9463.592490] Lustre: Unmounted lustre-client [ 9492.290308] Key type lgssc unregistered [ 9492.581703] LNet: 180948:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9492.603529] LNetError: 180948:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9492.641353] LNet: Removed LNI 192.168.204.5@tcp [ 9493.611115] Key type .llcrypt unregistered [ 9493.613282] Key type ._llcrypt unregistered [ 9509.550583] Key type ._llcrypt registered [ 9509.553494] Key type .llcrypt registered [ 9510.854833] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 04:53:01 (1786956781) [ 9532.222865] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9532.244271] alg: No test for adler32 (adler32-zlib) [ 9533.433963] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [ 9533.739823] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [ 9535.505878] Key type lgssc registered [ 9536.703373] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9623.540953] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9629.761502] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9656.116767] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9706.000549] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 04:56:16 (1786956976) [ 9738.119914] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9743.900272] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9764.224134] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9766.048556] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [ 9771.075163] Lustre: Unmounted lustre-client [ 9806.346471] Key type lgssc unregistered [ 9806.601574] LNet: 188055:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9806.616367] LNetError: 188055:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9806.636146] LNet: Removed LNI 192.168.204.5@tcp [ 9807.364289] Key type .llcrypt unregistered [ 9807.366941] Key type ._llcrypt unregistered [ 9822.916786] Key type ._llcrypt registered [ 9822.919812] Key type .llcrypt registered [ 9824.417729] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 9825.999801] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 04:58:16 (1786957096) [ 9845.707877] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9845.775591] alg: No test for adler32 (adler32-zlib) [ 9846.897172] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [ 9847.175258] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [ 9848.855255] Key type lgssc registered [ 9850.350976] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9948.238808] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9955.143760] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9978.344442] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9999.756261] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10001.422751] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [10023.415964] Lustre: Unmounted lustre-client [10170.455241] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10177.629782] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10201.020907] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10254.744842] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 05:05:25 (1786957525) [10257.371133] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [10259.647071] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 05:05:29 (1786957529) [10357.143985] Key type lgssc unregistered [10357.464758] LNet: 199516:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10357.474274] LNetError: 199516:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10357.503893] LNet: Removed LNI 192.168.204.5@tcp [10358.263225] Key type .llcrypt unregistered [10358.264726] Key type ._llcrypt unregistered [10373.490457] Key type ._llcrypt registered [10373.495253] Key type .llcrypt registered [10374.639607] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 05:07:25 (1786957645) [10414.285584] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10414.409119] alg: No test for adler32 (adler32-zlib) [10415.424576] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [10415.630160] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [10417.299083] Key type lgssc registered [10418.400260] Lustre: Echo OBD driver; http://www.lustre.org/ [10466.665725] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10472.672555] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10508.083473] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10534.576750] Key type lgssc unregistered [10534.888729] LNet: 203252:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10534.896539] LNetError: 203252:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10534.920651] LNet: Removed LNI 192.168.204.5@tcp [10535.813869] Key type .llcrypt unregistered [10535.823054] Key type ._llcrypt unregistered [10553.318408] Key type ._llcrypt registered [10553.326324] Key type .llcrypt registered [10555.318646] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 05:10:25 (1786957825) [10558.310463] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10558.391604] alg: No test for adler32 (adler32-zlib) [10559.693605] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [10560.132461] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [10561.895196] Key type lgssc registered [10563.805721] Lustre: Echo OBD driver; http://www.lustre.org/ [10627.365733] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10634.802443] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10659.600805] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10666.568500] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [10676.729903] Lustre: lustre-MDT0000-mdc-ffff979fd13b3800: Connection to lustre-MDT0000 (at 192.168.204.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [10686.961334] Lustre: lustre-MDT0001-mdc-ffff979fd13b3800: Connection to lustre-MDT0001 (at 192.168.204.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [10697.183418] Lustre: 204241:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786957953/real 1786957953] req@00000000d546d0d9 x1873761095729408/t0(0) o400->MGC192.168.204.105@tcp@192.168.204.105@tcp:26/25 lens 224/224 e 0 to 1 dl 1786957969 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10697.208307] LustreError: MGC192.168.204.105@tcp: Connection to MGS (at 192.168.204.105@tcp) was lost; in progress operations using this service will fail [10723.818267] Lustre: Evicted from MGS (at 192.168.204.105@tcp) after server handle changed from 0xbbbd606f7ed87256 to 0xbbbd606f7ed88f13 [10723.845138] Lustre: MGC192.168.204.105@tcp: Connection restored to 192.168.204.105@tcp (at 192.168.204.105@tcp) [10733.035471] LustreError: lustre-MDT0000-mdc-ffff979fd13b3800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [10733.070799] Lustre: lustre-MDT0000-mdc-ffff979fd13b3800: Connection restored to 192.168.204.105@tcp (at 192.168.204.105@tcp) [10739.139564] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10740.981457] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10746.333163] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10748.439942] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [10752.646780] Lustre: Unmounted lustre-client [10781.124991] Key type lgssc unregistered [10781.414503] LNet: 208207:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10781.421696] LNetError: 208207:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10781.448692] LNet: Removed LNI 192.168.204.5@tcp [10782.044567] Key type .llcrypt unregistered [10782.048784] Key type ._llcrypt unregistered [10797.470986] Key type ._llcrypt registered [10797.473458] Key type .llcrypt registered [10798.671802] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 05:14:29 (1786958069) [10801.638672] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10801.660618] alg: No test for adler32 (adler32-zlib) [10802.766578] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [10802.986784] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [10804.648705] Key type lgssc registered [10805.821440] Lustre: Echo OBD driver; http://www.lustre.org/ [10857.642831] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10863.361296] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10928.184798] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10933.942486] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10976.901856] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11003.894714] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11049.483883] Key type lgssc unregistered [11049.859235] LNet: 213510:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11049.869630] LNetError: 213510:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11049.918232] LNet: Removed LNI 192.168.204.5@tcp [11050.920942] Key type .llcrypt unregistered [11050.922710] Key type ._llcrypt unregistered [11101.254474] Key type ._llcrypt registered [11101.263194] Key type .llcrypt registered [11102.774816] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 05:19:33 (1786958373) [11139.739191] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11139.760739] alg: No test for adler32 (adler32-zlib) [11140.814033] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [11141.021238] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [11142.703298] Key type lgssc registered [11144.168155] Lustre: Echo OBD driver; http://www.lustre.org/ [11232.262712] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11238.315660] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11261.614403] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11311.041729] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 05:23:01 (1786958581) [11318.379142] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 05:23:09 (1786958589) [11358.976537] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11365.561191] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11391.744404] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11399.254914] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [11405.634225] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [11409.113315] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [11410.837458] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [11412.510655] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [11419.636936] Lustre: lustre-MDT0000-mdc-ffff979fc8f90800: Connection to lustre-MDT0000 (at 192.168.204.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [11436.001093] Lustre: 215408:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786958691/real 1786958691] req@00000000080a17ac x1873761704880512/t0(0) o400->MGC192.168.204.105@tcp@192.168.204.105@tcp:26/25 lens 224/224 e 0 to 1 dl 1786958707 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [11436.047062] LustreError: MGC192.168.204.105@tcp: Connection to MGS (at 192.168.204.105@tcp) was lost; in progress operations using this service will fail [11436.086406] Lustre: Evicted from MGS (at 192.168.204.105@tcp) after server handle changed from 0x837c60196cd30a4f to 0x837c60196cd30bc9 [11436.103727] Lustre: MGC192.168.204.105@tcp: Connection restored to 192.168.204.105@tcp (at 192.168.204.105@tcp) [11443.710872] Lustre: 221405:0:(mgc_request.c:1899:mgc_process_log()) MGC192.168.204.105@tcp: IR log lustre-cliir failed, not fatal: rc = -2 [11449.884882] Lustre: lustre-MDT0000-mdc-ffff979fc8f90800: Connection restored to 192.168.204.105@tcp (at 192.168.204.105@tcp) [11460.379251] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11462.336296] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [11467.181264] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [11471.866761] Lustre: lustre-MDT0000-mdc-ffff979fc8f90800: Connection to lustre-MDT0000 (at 192.168.204.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [11487.200037] Lustre: 215410:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786958743/real 1786958743] req@000000003a55f936 x1873761704890368/t0(0) o400->MGC192.168.204.105@tcp@192.168.204.105@tcp:26/25 lens 224/224 e 0 to 1 dl 1786958759 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [11487.241424] LustreError: MGC192.168.204.105@tcp: Connection to MGS (at 192.168.204.105@tcp) was lost; in progress operations using this service will fail [11495.919830] Lustre: lustre-MDT0000-mdc-ffff979fc8f90800: Connection restored to 192.168.204.105@tcp (at 192.168.204.105@tcp) [11497.957932] Lustre: Evicted from MGS (at 192.168.204.105@tcp) after server handle changed from 0x837c60196cd30bc9 to 0x837c60196cd31172 [11497.976732] Lustre: MGC192.168.204.105@tcp: Connection restored to 192.168.204.105@tcp (at 192.168.204.105@tcp) [11506.744450] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11508.578639] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [11513.104026] Lustre: Unmounted lustre-client [11585.543355] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11591.183717] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11614.172720] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11614.879563] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [11622.129230] Lustre: Unmounted lustre-client [11650.097801] Key type lgssc unregistered [11650.352095] LNet: 226009:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11650.366895] LNetError: 226009:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11650.389189] LNet: Removed LNI 192.168.204.5@tcp [11650.979846] Key type .llcrypt unregistered [11650.984528] Key type ._llcrypt unregistered [11666.047568] Key type ._llcrypt registered [11666.052337] Key type .llcrypt registered [11667.242364] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 05:28:57 (1786958937) [11670.813239] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11670.970853] alg: No test for adler32 (adler32-zlib) [11672.054486] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [11672.317654] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [11673.999363] Key type lgssc registered [11675.332619] Lustre: Echo OBD driver; http://www.lustre.org/ [11726.716563] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11731.697843] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11751.216385] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11753.843556] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [11759.526330] Lustre: Unmounted lustre-client [11828.847229] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11835.930141] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11860.421846] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11861.013464] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [11870.644201] Lustre: Unmounted lustre-client [11947.488681] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11953.690540] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11976.584432] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11977.394583] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [11980.865640] Lustre: Unmounted lustre-client [12009.895285] Key type lgssc unregistered [12010.276902] LNet: 233058:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12010.283508] LNetError: 233058:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12010.301468] LNet: Removed LNI 192.168.204.5@tcp [12011.039928] Key type .llcrypt unregistered [12011.043385] Key type ._llcrypt unregistered [12029.706341] Key type ._llcrypt registered [12029.708427] Key type .llcrypt registered [12032.030311] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 05:35:01 (1786959301) [12037.188501] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12037.227888] alg: No test for adler32 (adler32-zlib) [12038.612568] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [12039.000909] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [12040.727677] Key type lgssc registered [12042.133633] Lustre: Echo OBD driver; http://www.lustre.org/ [12092.044838] Key type lgssc unregistered [12092.419762] LNet: 235383:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12092.426764] LNetError: 235383:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12092.441011] LNet: Removed LNI 192.168.204.5@tcp [12093.310717] Key type .llcrypt unregistered [12093.313729] Key type ._llcrypt unregistered [12123.467516] Key type ._llcrypt registered [12123.470254] Key type .llcrypt registered [12123.911156] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12123.929220] alg: No test for adler32 (adler32-zlib) [12125.092156] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [12125.358263] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [12127.129918] Key type lgssc registered [12128.322596] Lustre: Echo OBD driver; http://www.lustre.org/ [12216.707913] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12223.501876] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12247.332226] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12298.865220] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [12301.150346] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 05:39:31 (1786959571) [12338.283938] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12343.958212] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12365.121826] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12389.033637] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12390.962155] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [12396.014316] Lustre: lustre-OST0001-osc-ffff979fda719800: Connection to lustre-OST0001 (at 192.168.204.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [12403.720623] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12414.071701] Lustre: Unmounted lustre-client [12457.336200] Key type lgssc unregistered [12457.686979] LNet: 242502:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12457.697185] LNetError: 242502:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12457.715042] LNet: Removed LNI 192.168.204.5@tcp [12458.456361] Key type .llcrypt unregistered [12458.459540] Key type ._llcrypt unregistered [12490.339775] Key type ._llcrypt registered [12490.342774] Key type .llcrypt registered [12491.737710] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 05:42:42 (1786959762) [12504.326433] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12504.373652] alg: No test for adler32 (adler32-zlib) [12505.441471] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [12505.827328] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [12507.559345] Key type lgssc registered [12508.825410] Lustre: Echo OBD driver; http://www.lustre.org/ [12569.170492] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12576.137615] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12602.016083] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 05:44:32 (1786959872) [12638.710594] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12644.924974] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12668.880646] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12670.508968] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [12679.210943] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [12689.650684] Lustre: Unmounted lustre-client [12800.068655] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12807.647980] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12861.219389] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12861.908806] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [12864.838111] Lustre: Unmounted lustre-client [12894.635650] Key type lgssc unregistered [12894.949170] LNet: 250198:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12894.972192] LNetError: 250198:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12894.998961] LNet: Removed LNI 192.168.204.5@tcp [12896.222422] Key type .llcrypt unregistered [12896.224621] Key type ._llcrypt unregistered [12929.271801] Key type ._llcrypt registered [12929.287955] Key type .llcrypt registered [12929.853973] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12929.882348] alg: No test for adler32 (adler32-zlib) [12930.990889] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [12931.305029] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [12932.991441] Key type lgssc registered [12934.553622] Lustre: Echo OBD driver; http://www.lustre.org/ [13033.386803] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13040.931959] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13066.909795] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13114.308730] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [13116.737210] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 05:53:06 (1786960386) [13153.342937] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13159.900518] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13183.860690] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13188.478226] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [13192.802251] Lustre: Unmounted lustre-client [13219.515127] Key type lgssc unregistered [13219.825484] LNet: 256847:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13219.842123] LNetError: 256847:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13219.870638] LNet: Removed LNI 192.168.204.5@tcp [13220.619334] Key type .llcrypt unregistered [13220.623742] Key type ._llcrypt unregistered [13235.112696] Key type ._llcrypt registered [13235.114991] Key type .llcrypt registered [13236.840875] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [13238.567715] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 05:55:09 (1786960509) [13247.835440] Key type .llcrypt unregistered [13247.839023] Key type ._llcrypt unregistered [13256.721162] Key type ._llcrypt registered [13256.725636] Key type .llcrypt registered [13257.127346] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13257.139632] alg: No test for adler32 (adler32-zlib) [13258.131849] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [13258.383677] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [13260.144147] Key type lgssc registered [13261.503049] Lustre: Echo OBD driver; http://www.lustre.org/ [13326.730835] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13344.690683] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [13347.576458] Lustre: Unmounted lustre-client [13367.908232] Key type lgssc unregistered [13368.114273] LNet: 260310:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13368.125967] LNetError: 260310:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13368.144148] LNet: Removed LNI 192.168.204.5@tcp [13369.019077] Key type .llcrypt unregistered [13369.021424] Key type ._llcrypt unregistered [13386.708901] Key type ._llcrypt registered [13386.712854] Key type .llcrypt registered [13388.273967] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 05:57:38 (1786960658) [13390.515399] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13390.542541] alg: No test for adler32 (adler32-zlib) [13391.782525] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [13392.176869] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [13393.943386] Key type lgssc registered [13395.768735] Lustre: Echo OBD driver; http://www.lustre.org/ [13482.599297] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13517.425385] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13524.287592] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13527.082056] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [13529.921408] Lustre: Unmounted lustre-client [13558.281231] Key type lgssc unregistered [13558.598228] LNet: 263867:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13558.611783] LNetError: 263867:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13559.655945] LNet: Removed LNI 192.168.204.5@tcp [13560.351324] Key type .llcrypt unregistered [13560.356145] Key type ._llcrypt unregistered [13576.365991] Key type ._llcrypt registered [13576.368233] Key type .llcrypt registered [13577.536910] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 06:00:48 (1786960848) [13580.221053] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13580.270832] alg: No test for adler32 (adler32-zlib) [13581.408834] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [13581.717880] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [13583.391188] Key type lgssc registered [13584.979102] Lustre: Echo OBD driver; http://www.lustre.org/ [13639.195677] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13645.212609] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13667.412374] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13670.218753] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [13675.508684] Lustre: lustre-MDT0000-mdc-ffff979fd055e000: Connection to lustre-MDT0000 (at 192.168.204.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [13677.103054] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [13677.145970] LustreError: 267036:0:(file.c:6166:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [13677.707486] LustreError: 267055:0:(file.c:6166:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [13677.724548] LustreError: 267055:0:(file.c:6166:ll_inode_revalidate_fini()) Skipped 5 previous similar messages [13677.760425] LustreError: MGC192.168.204.105@tcp: Connection to MGS (at 192.168.204.105@tcp) was lost; in progress operations using this service will fail [13677.841954] Lustre: Unmounted lustre-client [13696.238775] Key type lgssc unregistered [13696.534854] LNet: 267452:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13696.550269] LNetError: 267452:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13696.572980] LNet: Removed LNI 192.168.204.5@tcp [13697.234082] Key type .llcrypt unregistered [13697.243199] Key type ._llcrypt unregistered [13709.851752] Key type ._llcrypt registered [13709.853949] Key type .llcrypt registered [13711.204832] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 06:03:01 (1786960981) [13713.359709] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13713.383885] alg: No test for adler32 (adler32-zlib) [13714.483200] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [13714.706591] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [13716.367373] Key type lgssc registered [13717.686463] Lustre: Echo OBD driver; http://www.lustre.org/ [13768.806440] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13774.206173] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13797.250976] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13800.111092] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [13805.566640] Lustre: lustre-MDT0001-mdc-ffff979fc6be2000: Connection to lustre-MDT0001 (at 192.168.204.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [13810.367677] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [13811.985200] Lustre: Unmounted lustre-client [13830.372305] Key type lgssc unregistered [13830.622657] LNet: 271038:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13830.633847] LNetError: 271038:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13830.654378] LNet: Removed LNI 192.168.204.5@tcp [13831.385662] Key type .llcrypt unregistered [13831.388248] Key type ._llcrypt unregistered [13847.036325] Key type ._llcrypt registered [13847.043849] Key type .llcrypt registered [13848.742049] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 06:05:18 (1786961118) [13866.877465] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13866.970257] alg: No test for adler32 (adler32-zlib) [13868.086209] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [13868.337404] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [13870.055379] Key type lgssc registered [13871.593929] Lustre: Echo OBD driver; http://www.lustre.org/ [13974.591039] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13976.182374] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [14007.277422] Lustre: lustre-MDT0001-mdc-ffff979fe07dd800: Connection to lustre-MDT0001 (at 192.168.204.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [14018.993567] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [14029.376282] Lustre: Unmounted lustre-client [14057.179893] Key type lgssc unregistered [14057.464497] LNet: 276829:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14057.475500] LNetError: 276829:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14057.513504] LNet: Removed LNI 192.168.204.5@tcp [14058.128167] Key type .llcrypt unregistered [14058.138704] Key type ._llcrypt unregistered [14072.106350] Key type ._llcrypt registered [14072.109127] Key type .llcrypt registered [14073.519571] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 06:09:03 (1786961343) [14075.189088] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [14076.746838] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 06:09:07 (1786961347) [14078.681126] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [14080.775276] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 06:09:11 (1786961351) [14082.588854] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [14085.041281] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 06:09:15 (1786961355) [14087.485451] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [14089.438503] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 06:09:19 (1786961359) [14091.014662] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [14093.099217] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 06:09:23 (1786961363) [14102.172888] Key type .llcrypt unregistered [14102.174865] Key type ._llcrypt unregistered [14108.745996] Key type ._llcrypt registered [14108.748760] Key type .llcrypt registered [14109.184295] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14109.221787] alg: No test for adler32 (adler32-zlib) [14110.276665] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [14110.531797] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [14112.271298] Key type lgssc registered [14113.570496] Lustre: Echo OBD driver; http://www.lustre.org/ [14183.471922] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14189.277420] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14214.346378] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14217.283597] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [14220.768338] Lustre: Unmounted lustre-client [14251.204633] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 06:12:01 (1786961521) [14288.480412] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14295.289117] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14331.388705] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14331.999990] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [14334.795200] Lustre: Unmounted lustre-client [14386.079125] LNet: 1 local NIs in recovery (showing 1): 192.168.204.5@tcp [14388.387745] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 06:14:19 (1786961659) [15038.330715] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15043.947369] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15077.332277] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15170.303668] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 06:27:20 (1786962440) [15180.736979] Key type lgssc unregistered [15180.963752] LNet: 290371:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15180.971060] LNetError: 290371:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15181.994166] LNet: Removed LNI 192.168.204.5@tcp [15182.746156] Key type .llcrypt unregistered [15182.748097] Key type ._llcrypt unregistered [15319.341511] Key type ._llcrypt registered [15319.343665] Key type .llcrypt registered [15320.042219] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15320.075291] alg: No test for adler32 (adler32-zlib) [15321.322883] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [15321.794319] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [15323.624706] Key type lgssc registered [15325.072894] Lustre: Echo OBD driver; http://www.lustre.org/ [15420.599818] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15427.477543] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15451.532671] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15483.566392] Key type lgssc unregistered [15483.831618] LNet: 294097:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15483.845888] LNetError: 294097:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15483.865083] LNet: Removed LNI 192.168.204.5@tcp [15484.586675] Key type .llcrypt unregistered [15484.588192] Key type ._llcrypt unregistered [15521.879769] Key type ._llcrypt registered [15521.881949] Key type .llcrypt registered [15523.290915] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 06:33:13 (1786962793) [15542.782140] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15542.799388] alg: No test for adler32 (adler32-zlib) [15544.000833] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [15544.280750] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [15545.999216] Key type lgssc registered [15547.190969] Lustre: Echo OBD driver; http://www.lustre.org/ [15593.101890] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 06:34:23 (1786962863) [15604.668990] Key type lgssc unregistered [15604.882492] LNet: 297662:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15604.888364] LNetError: 297662:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15604.905600] LNet: Removed LNI 192.168.204.5@tcp [15605.686825] Key type .llcrypt unregistered [15605.689317] Key type ._llcrypt unregistered [15615.887844] Key type ._llcrypt registered [15615.890422] Key type .llcrypt registered [15616.393113] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15616.413705] alg: No test for adler32 (adler32-zlib) [15617.578381] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [15617.833049] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [15619.527953] Key type lgssc registered [15620.652466] Lustre: Echo OBD driver; http://www.lustre.org/ [15680.708273] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15687.158553] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15713.555575] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16007.700776] LNet: Added LNI 192.168.204.5@tcp50 [8/256/0/180] [16010.263887] LNet: Removed LNI 192.168.204.5@tcp [16012.452456] Lustre: 302079:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff979fd10eb800: config has no valid NIDs, force dynamic NIDs [16013.683467] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [16013.867351] Lustre: Unmounted lustre-client [16016.776939] Key type lgssc unregistered [16017.092866] LNet: 302339:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16017.103673] LNetError: 302339:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16018.156154] LNet: Removed LNI 192.168.204.5@tcp50 [16018.933205] Key type .llcrypt unregistered [16018.937617] Key type ._llcrypt unregistered [16036.792337] Key type ._llcrypt registered [16036.794202] Key type .llcrypt registered [16037.342497] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [16037.359788] alg: No test for adler32 (adler32-zlib) [16038.478520] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [16038.799826] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [16040.601335] Key type lgssc registered [16041.984822] Lustre: Echo OBD driver; http://www.lustre.org/ [16106.442994] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16113.090659] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16122.452225] LNet: Added LNI 192.168.204.5@tcp50 [8/256/0/180] [16123.534209] LNet: Removed LNI 192.168.204.5@tcp [16124.755290] Lustre: 305036:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff979fe07dd800: config has no valid NIDs, force dynamic NIDs [16126.078591] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [16126.844597] Lustre: Unmounted lustre-client [16161.332517] Key type lgssc unregistered [16161.678337] LNet: 305908:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16161.684942] LNetError: 305908:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16161.710723] LNet: Removed LNI 192.168.204.5@tcp50 [16162.365630] Key type .llcrypt unregistered [16162.371696] Key type ._llcrypt unregistered [16175.658701] Key type ._llcrypt registered [16175.661116] Key type .llcrypt registered [16177.260749] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 06:44:07 (1786963447) [16195.211802] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16195.260615] alg: No test for adler32 (adler32-zlib) [16196.563329] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [16196.895992] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [16198.608113] Key type lgssc registered [16200.339498] Lustre: Echo OBD driver; http://www.lustre.org/ [16292.850251] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16299.625766] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16326.798786] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16370.006987] Key type lgssc unregistered [16370.311984] LNet: 310666:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16370.320844] LNetError: 310666:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16370.335114] LNet: Removed LNI 192.168.204.5@tcp [16371.287846] Key type .llcrypt unregistered [16371.291395] Key type ._llcrypt unregistered [16384.148485] Key type ._llcrypt registered [16384.160062] Key type .llcrypt registered [16385.956813] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 06:47:35 (1786963655) [16389.038911] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16389.075749] alg: No test for adler32 (adler32-zlib) [16390.361945] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [16390.832306] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [16392.663344] Key type lgssc registered [16394.668341] Lustre: Echo OBD driver; http://www.lustre.org/ [16451.813773] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16458.401261] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16481.604827] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16483.184776] Lustre: Mounted lustre-client - version 2.17.57_2_g91a1b42 [16548.404610] Lustre: Mounted fs15246-client - version 2.17.57_2_g91a1b42 [16563.621213] Lustre: Unmounted fs15246-client [16593.868077] Lustre: Unmounted lustre-client [16623.637252] Key type lgssc unregistered [16623.981342] LNet: 315534:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16624.000105] LNetError: 315534:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16624.069110] LNet: Removed LNI 192.168.204.5@tcp [16625.125963] Key type .llcrypt unregistered [16625.127995] Key type ._llcrypt unregistered [16638.527965] Key type ._llcrypt registered [16638.532992] Key type .llcrypt registered [16640.378487] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 06:51:50 (1786963910) [16642.676204] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16642.740531] alg: No test for adler32 (adler32-zlib) [16643.860259] Lustre: Lustre: Build Version: 2.17.57_2_g91a1b42 [16644.136095] LNet: Added LNI 192.168.204.5@tcp [8/256/0/180] [16645.967299] Key type lgssc registered [16647.207911] Lustre: Echo OBD driver; http://www.lustre.org/ [16799.592104] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16805.467973] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16834.379150] Lustre: DEBUG MARKER: oleg405-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16954.897956] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 16610 sec ========= 06:57:05 (1786964225) [16957.060784] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 06:57:07 (1786964227) === [16961.944144] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 06:57:11 (1786964231) === [17000.286240] Key type lgssc unregistered [17000.682281] LNet: 324810:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [17000.692334] LNetError: 324810:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [17000.718466] LNet: Removed LNI 192.168.204.5@tcp [17001.671993] Key type .llcrypt unregistered [17001.675083] Key type ._llcrypt unregistered