[ 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 481092607 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2400.000 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2895288K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524584K 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.001015] APIC: Switch to symmetric I/O mode setup [ 0.003382] x2apic enabled [ 0.004008] Switched APIC routing to physical x2apic. [ 0.005013] kvm-guest: setup PV IPIs [ 0.008000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x22983777dd9, max_idle_ns: 440795300422 ns [ 0.008027] Calibrating delay loop (skipped) preset value.. 4800.00 BogoMIPS (lpj=2400000) [ 0.009013] pid_max: default: 32768 minimum: 301 [ 0.011131] LSM: Security Framework initializing [ 0.012042] Yama: becoming mindful. [ 0.013039] SELinux: Initializing. [ 0.014076] *** VALIDATE selinux *** [ 0.022334] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.027111] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.029059] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030125] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.032029] *** VALIDATE tmpfs *** [ 0.034096] *** VALIDATE proc *** [ 0.035317] *** VALIDATE cgroup *** [ 0.036015] *** VALIDATE cgroup2 *** [ 0.038055] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.039185] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.040011] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.041040] Spectre V2 : User space: Vulnerable [ 0.042012] Speculative Store Bypass: Vulnerable [ 0.046014] debug: unmapping init [mem 0xffffffff98059000-0xffffffff98060fff] [ 0.049277] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.050740] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.051026] ... version: 2 [ 0.052016] ... bit width: 48 [ 0.053011] ... generic registers: 4 [ 0.054016] ... value mask: 0000ffffffffffff [ 0.055014] ... max period: 00007fffffffffff [ 0.056014] ... fixed-purpose events: 3 [ 0.057012] ... event mask: 000000070000000f [ 0.059265] rcu: Hierarchical SRCU implementation. [ 0.061779] smp: Bringing up secondary CPUs ... [ 0.062661] x86: Booting SMP configuration: [ 0.063023] .... node #0, CPUs: #1 #2 #3 [ 0.067246] smp: Brought up 1 node, 4 CPUs [ 0.070013] smpboot: Max logical packages: 1 [ 0.071011] smpboot: Total of 4 processors activated (19200.00 BogoMIPS) [ 0.185561] node 0 deferred pages initialised in 112ms [ 0.187174] devtmpfs: initialized [ 0.188212] x86/mm: Memory block size: 128MB [ 0.190685] gcov: version magic: 0x41383552 [ 0.192181] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.193142] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.194286] pinctrl core: initialized pinctrl subsystem [ 0.195154] [ 0.195525] ************************************************************* [ 0.196013] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.197008] ** ** [ 0.198007] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.199007] ** ** [ 0.200008] ** This means that this kernel is built to expose internal ** [ 0.201007] ** IOMMU data structures, which may compromise security on ** [ 0.202013] ** your system. ** [ 0.203008] ** ** [ 0.204009] ** If you see this message and you are not debugging the ** [ 0.205011] ** kernel, report this immediately to your vendor! ** [ 0.206010] ** ** [ 0.207011] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.208008] ************************************************************* [ 0.209881] NET: Registered protocol family 16 [ 0.210432] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.211050] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.212160] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.214099] cpuidle: using governor menu [ 0.216765] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.217512] PCI: Using configuration type 1 for base access [ 0.218172] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.228035] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.229080] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.231044] cryptd: max_cpu_qlen set to 1000 [ 0.233591] ACPI: Added _OSI(Module Device) [ 0.235009] ACPI: Added _OSI(Processor Device) [ 0.236007] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.237007] ACPI: Added _OSI(Processor Aggregator Device) [ 0.242598] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.248413] ACPI: Interpreter enabled [ 0.249043] ACPI: PM: (supports S0 S3 S4 S5) [ 0.250008] ACPI: Using IOAPIC for interrupt routing [ 0.251084] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.252414] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.265412] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.266027] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.267020] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.268137] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.271778] acpiphp: Slot [2] registered [ 0.272095] acpiphp: Slot [5] registered [ 0.273120] acpiphp: Slot [6] registered [ 0.274094] acpiphp: Slot [3] registered [ 0.275073] acpiphp: Slot [4] registered [ 0.276108] acpiphp: Slot [7] registered [ 0.277087] acpiphp: Slot [8] registered [ 0.278080] acpiphp: Slot [9] registered [ 0.279089] acpiphp: Slot [10] registered [ 0.280073] acpiphp: Slot [11] registered [ 0.281086] acpiphp: Slot [12] registered [ 0.282164] acpiphp: Slot [13] registered [ 0.283081] acpiphp: Slot [14] registered [ 0.284086] acpiphp: Slot [15] registered [ 0.285077] acpiphp: Slot [16] registered [ 0.286155] acpiphp: Slot [17] registered [ 0.287154] acpiphp: Slot [18] registered [ 0.288121] acpiphp: Slot [19] registered [ 0.289178] acpiphp: Slot [20] registered [ 0.290115] acpiphp: Slot [21] registered [ 0.291081] acpiphp: Slot [22] registered [ 0.292101] acpiphp: Slot [23] registered [ 0.293115] acpiphp: Slot [24] registered [ 0.294086] acpiphp: Slot [25] registered [ 0.295083] acpiphp: Slot [26] registered [ 0.296078] acpiphp: Slot [27] registered [ 0.297079] acpiphp: Slot [28] registered [ 0.298081] acpiphp: Slot [29] registered [ 0.299079] acpiphp: Slot [30] registered [ 0.300073] acpiphp: Slot [31] registered [ 0.301055] PCI host bridge to bus 0000:00 [ 0.302017] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.303041] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.304020] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.305022] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.306016] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.307025] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.308183] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.310264] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.312328] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.317918] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.320428] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.321010] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.322019] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.323027] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.324651] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.325777] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.326046] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.331167] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.336011] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.347029] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.353868] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.359325] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.365012] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.370013] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.382013] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.391466] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.399015] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.414014] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.441017] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.450574] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.453334] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.456409] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.458312] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.460421] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.466034] iommu: Default domain type: Passthrough [ 0.469364] SCSI subsystem initialized [ 0.470152] ACPI: bus type USB registered [ 0.472080] usbcore: registered new interface driver usbfs [ 0.473095] usbcore: registered new interface driver hub [ 0.475047] usbcore: registered new device driver usb [ 0.477135] pps_core: LinuxPPS API ver. 1 registered [ 0.479024] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.482050] PTP clock support registered [ 0.484057] EDAC MC: Ver: 3.0.0 [ 0.485374] PCI: Using ACPI for IRQ routing [ 0.488191] NetLabel: Initializing [ 0.490007] NetLabel: domain hash size = 128 [ 0.492007] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.493081] NetLabel: unlabeled traffic allowed by default [ 0.495102] vgaarb: loaded [ 0.496571] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.498008] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.504949] clocksource: Switched to clocksource kvm-clock [ 0.625328] VFS: Disk quotas dquot_6.6.0 [ 0.627326] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.630117] *** VALIDATE ramfs *** [ 0.631212] *** VALIDATE hugetlbfs *** [ 0.632527] pnp: PnP ACPI init [ 0.635218] pnp: PnP ACPI: found 6 devices [ 0.650352] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.654311] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.656085] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.657692] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.659526] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.661755] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.665051] NET: Registered protocol family 2 [ 0.667464] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.672467] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.676191] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.681987] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.685121] TCP: Hash tables configured (established 65536 bind 65536) [ 0.687939] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.690778] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.693445] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.696478] NET: Registered protocol family 1 [ 0.699079] RPC: Registered named UNIX socket transport module. [ 0.700764] RPC: Registered udp transport module. [ 0.704662] RPC: Registered tcp transport module. [ 0.706166] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.708544] NET: Registered protocol family 44 [ 0.713198] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.716632] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.719417] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.721226] PCI: CLS 0 bytes, default 64 [ 0.722631] Unpacking initramfs... [ 2.471709] debug: unmapping init [mem 0xffff96cf7cc64000-0xffff96cf7ffcffff] [ 2.475581] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.477727] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.480450] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x22983777dd9, max_idle_ns: 440795300422 ns [ 3.060890] Initialise system trusted keyrings [ 3.062477] Key type blacklist registered [ 3.064368] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 3.073928] zbud: loaded [ 3.076854] *** VALIDATE nfs *** [ 3.078030] *** VALIDATE nfs4 *** [ 3.079601] pstore: using deflate compression [ 3.083374] Platform Keyring initialized [ 3.179275] NET: Registered protocol family 38 [ 3.181301] Key type asymmetric registered [ 3.182626] Asymmetric key parser 'x509' registered [ 3.184889] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.188059] io scheduler mq-deadline registered [ 3.189112] io scheduler kyber registered [ 3.190114] io scheduler bfq registered [ 3.194528] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.198294] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.203280] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.205155] ACPI: Power Button [PWRF] [ 3.208772] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.213657] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.231742] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.259042] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.286014] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.290515] Non-volatile memory driver v1.3 [ 3.291521] Linux agpgart interface v0.103 [ 3.312167] virtio_blk virtio1: [vda] 150504 512-byte logical blocks (77.1 MB/73.5 MiB) [ 3.313786] vda: detected capacity change from 0 to 77058048 [ 3.333959] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.336534] vdb: detected capacity change from 0 to 1073741824 [ 3.350924] libphy: Fixed MDIO Bus: probed [ 3.365263] usbcore: registered new interface driver usbserial_generic [ 3.370933] usbserial: USB Serial support registered for generic [ 3.373261] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.378078] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.379930] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.383265] mousedev: PS/2 mouse device common for all mice [ 3.386424] rtc_cmos 00:05: RTC can wake from S4 [ 3.395636] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.397867] rtc_cmos 00:05: registered as rtc0 [ 3.400698] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.404541] intel_pstate: CPU model not supported [ 3.406940] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.413091] hid: raw HID events driver (C) Jiri Kosina [ 3.414948] usbcore: registered new interface driver usbhid [ 3.417260] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.418781] usbhid: USB HID core driver [ 3.424300] drop_monitor: Initializing network drop monitor service [ 3.426856] Initializing XFRM netlink socket [ 3.428887] NET: Registered protocol family 10 [ 3.431530] Segment Routing with IPv6 [ 3.433039] NET: Registered protocol family 17 [ 3.435029] mpls_gso: MPLS GSO support [ 3.441328] RAS: Correctable Errors collector initialized. [ 3.444699] AVX version of gcm_enc/dec engaged. [ 3.447327] AES CTR mode by8 optimization enabled [ 3.538811] sched_clock: Marking stable (3538792578, 0)->(4527196982, -988404404) [ 3.542238] registered taskstats version 1 [ 3.543858] Loading compiled-in X.509 certificates [ 3.545607] zswap: loaded using pool lzo/zbud [ 3.634887] Key type big_key registered [ 3.659486] Key type encrypted registered [ 3.660915] ima: No TPM chip found, activating TPM-bypass! [ 3.664448] ima: Allocated hash algorithm: sha1 [ 3.666500] ima: No architecture policies found [ 3.668357] evm: Initialising EVM extended attributes: [ 3.670154] evm: security.selinux [ 3.671419] evm: security.ima [ 3.672529] evm: security.capability [ 3.673777] evm: HMAC attrs: 0x1 [ 3.676195] rtc_cmos 00:05: setting system clock to 2026-09-08 20:50:32 UTC (1788900632) [ 3.682750] debug: unmapping init [mem 0xffffffff99003000-0xffffffff991fffff] [ 3.690267] debug: unmapping init [mem 0xffffffff97d82000-0xffffffff98058fff] [ 3.722187] Write protecting the kernel read-only data: 28672k [ 3.727238] debug: unmapping init [mem 0xffffffff96403000-0xffffffff965fffff] [ 3.730064] debug: unmapping init [mem 0xffffffff96d14000-0xffffffff96dfffff] [ 3.766213] 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.782523] systemd[1]: Detected virtualization kvm. [ 3.784864] systemd[1]: Detected architecture x86-64. [ 3.788626] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.825584] systemd[1]: No hostname configured. [ 3.826985] systemd[1]: Set hostname to . [ 3.828816] random: systemd: uninitialized urandom read (16 bytes read) [ 3.830582] systemd[1]: Initializing machine ID from random generator. [ 3.947983] random: ln: uninitialized urandom read (6 bytes read) [ 4.095748] random: systemd: uninitialized urandom read (16 bytes read) [ 4.100546] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 4.111840] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 4.128605] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Swap. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on Journal Socket. Starting Journal Service... [ OK ] Started Memstrack Anylazing Service. [ OK ] Reached target Slices. [ OK ] Listening on udev Kernel Socket. Starting Setup Virtual Console... Starting Apply Kernel Variables... [ OK ] Reached target Sockets. [ OK ] Reached target Timers. [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 5.217973] device-mapper: uevent: version 1.0.3 [ 5.220904] 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. [ 6.259609] random: fast init done [ 6.267106] scsi host0: ata_piix [ 6.275760] virtio_net virtio0 ens2: renamed from eth0 [ 6.294396] scsi host1: ata_piix [ 6.310265] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 6.312629] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 11.191857] random: crng init done [ 11.193368] random: 7 urandom warning(s) missed due to ratelimiting [ 11.398310] dracut-initqueue[581]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 13.318327] 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... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Timers. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Swap. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 15.255881] printk: systemd: 26 output lines suppressed due to ratelimiting [ 15.658057] SELinux: Disabled at runtime. [ 15.735430] 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) [ 15.742426] systemd[1]: Detected virtualization kvm. [ 15.744409] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 16.443874] systemd[1]: initrd-switch-root.service: Succeeded. [ 16.447796] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 16.456389] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 16.461275] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 16.465790] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 16.475373] systemd[1]: Starting Journal Service... Starting Journal Service... [ 16.482631] systemd[1]: Created slice User and Session Slice. [ OK ] Created slice User and Session Slice. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on udev Control Socket. [ OK ] Listening on Process Core Dump Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Created slice system-sshd\x2dkeygen.slice. Mounting Kernel Debug File System... [ OK ] Reached target rpc_pipefs.target. [ OK ] Reached target Slices. [ OK ] Listening on RPCbind Server Activation Socket. Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on initctl Compatibility Named Pipe. Mounting Huge Pages File System... Mounting POSIX Message Queue File System... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Stopped target Initrd Root File System. [ 16.705340] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Created slice system-getty.slice. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. Starting Apply Kernel Variables... Starting Remount Root and Kernel File Systems... [ OK ] Reached target RPC Port Mapper. [ OK ] Started Journal Service. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Kernel Debug File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Apply Kernel Variables. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. Starting Configure read-only root support... [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... Starting udev Kernel Device Manager... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 17.415370] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 18.221777] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 18.308142] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 18.581056] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 18.682979] EDAC sbridge: Ver: 1.1.2 [ 20.683869] Key type dns_resolver registered [ 21.058922] NFS: Registering the id_resolver key type [ 21.060679] Key type id_resolver registered [ 21.062019] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... Starting Mark the need to relabel after reboot... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started D-Bus System Message Bus. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Login Service... Starting Network Manager... Starting Restore /run/initramfs on shutdown... [ OK ] Started irqbalance daemon. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Hostname Service... [ OK ] Started Login 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 OpenSSH server daemon. [ 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 Crash recovery kernel arming... Starting Notify NFS peers of a restart... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started 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. [ OK ] Started Authorization Manager. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg121-client login: [ 46.608704] libcfs: loading out-of-tree module taints kernel. [ 46.632475] Key type ._llcrypt registered [ 46.633379] Key type .llcrypt registered [ 46.898119] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 46.902474] alg: No test for adler32 (adler32-zlib) [ 47.876547] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 48.172526] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 49.783161] Key type lgssc registered [ 50.329219] Lustre: Echo OBD driver; http://www.lustre.org/ [ 108.285484] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 110.521227] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 116.967021] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing check_logdir /tmp/testlogs/ [ 118.469733] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing yml_node [ 120.020623] Lustre: DEBUG MARKER: Client: 2.17.58.5 [ 120.882499] Lustre: DEBUG MARKER: MDS: 2.17.58.5 [ 121.763096] Lustre: DEBUG MARKER: OSS: 2.17.58.5 [ 122.330036] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Tue Sep 8 16:52:30 EDT 2026 [ 127.842956] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 128.361743] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 128.879819] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 139.123522] Lustre: Unmounted lustre-client [ 212.883762] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 214.608555] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 222.582675] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 250.932217] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 16:54:39 (1788900879) [ 251.883089] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 252.490162] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 16:54:40 (1788900880) [ 262.926387] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 264.691288] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 270.882555] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 281.586798] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 283.407809] Lustre: Unmounted lustre-client [ 300.977695] Key type lgssc unregistered [ 301.096566] LNet: 14994:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 301.099909] LNetError: 14994:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 301.108832] LNet: Removed LNI 192.168.201.21@tcp [ 301.379173] Key type .llcrypt unregistered [ 301.380965] Key type ._llcrypt unregistered [ 306.469485] Key type ._llcrypt registered [ 306.471011] Key type .llcrypt registered [ 306.944069] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 16:55:35 (1788900935) [ 307.452189] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 307.458304] alg: No test for adler32 (adler32-zlib) [ 308.318351] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 308.413032] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 309.999152] Key type lgssc registered [ 310.423493] Lustre: Echo OBD driver; http://www.lustre.org/ [ 348.636279] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 424.116103] Lustre: Unmounted lustre-client [ 433.559880] Key type lgssc unregistered [ 433.681652] LNet: 18773:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 433.683886] LNetError: 18773:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 433.693552] LNet: Removed LNI 192.168.201.21@tcp [ 433.945078] Key type .llcrypt unregistered [ 433.946322] Key type ._llcrypt unregistered [ 437.389084] Key type ._llcrypt registered [ 437.390523] Key type .llcrypt registered [ 437.847247] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 16:57:46 (1788901066) [ 438.564928] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 438.587538] alg: No test for adler32 (adler32-zlib) [ 439.451901] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 439.546388] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 441.127190] Key type lgssc registered [ 441.532903] Lustre: Echo OBD driver; http://www.lustre.org/ [ 459.831811] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 461.697351] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 468.532603] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 479.218053] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 483.134629] Lustre: Unmounted lustre-client [ 501.283337] Key type lgssc unregistered [ 501.439749] LNet: 22359:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 501.446045] LNetError: 22359:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 501.455435] LNet: Removed LNI 192.168.201.21@tcp [ 501.809330] Key type .llcrypt unregistered [ 501.810461] Key type ._llcrypt unregistered [ 508.536670] Key type ._llcrypt registered [ 508.538447] Key type .llcrypt registered [ 509.477178] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 16:58:57 (1788901137) [ 511.109648] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 511.123128] alg: No test for adler32 (adler32-zlib) [ 512.071355] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 512.304253] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 513.935216] Key type lgssc registered [ 514.742471] Lustre: Echo OBD driver; http://www.lustre.org/ [ 558.479161] hrtimer: interrupt took 24169779 ns [ 566.953240] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 571.682870] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 584.999081] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 586.466799] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 590.553655] Lustre: Unmounted lustre-client [ 611.589588] Key type lgssc unregistered [ 611.817643] LNet: 25539:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 611.824772] LNetError: 25539:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 611.837738] LNet: Removed LNI 192.168.201.21@tcp [ 612.271243] Key type .llcrypt unregistered [ 612.272690] Key type ._llcrypt unregistered [ 621.649407] Key type ._llcrypt registered [ 621.651240] Key type .llcrypt registered [ 622.447042] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 17:00:50 (1788901250) [ 624.063124] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 624.103025] alg: No test for adler32 (adler32-zlib) [ 625.086260] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 625.229982] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 626.847261] Key type lgssc registered [ 627.627665] Lustre: Echo OBD driver; http://www.lustre.org/ [ 659.637973] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 663.040938] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 675.453495] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 677.858027] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 682.981768] Lustre: lustre-MDT0000-mdc-ffff96cfca89f800: Connection to lustre-MDT0000 (at 192.168.201.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 693.228908] Lustre: lustre-MDT0001-mdc-ffff96cfca89f800: Connection to lustre-MDT0001 (at 192.168.201.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 696.800398] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 703.455209] Lustre: 26493:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788901316/real 1788901316] req@00000000c095aee2 x1875798921320064/t0(0) o400->MGC192.168.201.121@tcp@192.168.201.121@tcp:26/25 lens 224/224 e 0 to 1 dl 1788901332 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 703.473646] LustreError: MGC192.168.201.121@tcp: Connection to MGS (at 192.168.201.121@tcp) was lost; in progress operations using this service will fail [ 707.081924] Lustre: Unmounted lustre-client [ 721.779258] Key type lgssc unregistered [ 721.969730] LNet: 29193:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 721.977120] LNetError: 29193:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 721.998263] LNet: Removed LNI 192.168.201.21@tcp [ 722.519114] Key type .llcrypt unregistered [ 722.521058] Key type ._llcrypt unregistered [ 734.596609] Key type ._llcrypt registered [ 734.599357] Key type .llcrypt registered [ 735.639422] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 17:02:43 (1788901363) [ 737.369133] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 737.391190] alg: No test for adler32 (adler32-zlib) [ 738.373442] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 738.538401] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 740.167189] Key type lgssc registered [ 741.140481] Lustre: Echo OBD driver; http://www.lustre.org/ [ 776.090437] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 779.961490] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 793.355716] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 805.384410] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 810.472481] Lustre: lustre-OST0000-osc-ffff96cfc3e19000: Connection to lustre-OST0000 (at 192.168.201.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 816.295967] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 826.642140] Lustre: Unmounted lustre-client [ 846.787455] Key type lgssc unregistered [ 846.969487] LNet: 32793:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 846.973875] LNetError: 32793:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 846.987208] LNet: Removed LNI 192.168.201.21@tcp [ 847.359791] Key type .llcrypt unregistered [ 847.361759] Key type ._llcrypt unregistered [ 855.816703] Key type ._llcrypt registered [ 855.818171] Key type .llcrypt registered [ 856.568038] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 17:04:44 (1788901484) [ 857.862886] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 857.878120] alg: No test for adler32 (adler32-zlib) [ 858.796620] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 858.912063] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 860.544148] Key type lgssc registered [ 861.151497] Lustre: Echo OBD driver; http://www.lustre.org/ [ 891.906470] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 895.469373] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 908.512526] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 911.008445] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 913.636853] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 915.771456] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 926.734326] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-09-0817h05m54s [ 927.891933] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 931.812469] Lustre: lustre-MDT0000-mdc-ffff96cfc6a33800: Connection to lustre-MDT0000 (at 192.168.201.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 936.606539] LustreError: 36569:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff96cfc6a33800: can't stat MDS #0: rc = -19 [ 937.495120] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 943.080716] LustreError: MGC192.168.201.121@tcp: Connection to MGS (at 192.168.201.121@tcp) was lost; in progress operations using this service will fail [ 943.092911] Lustre: Evicted from MGS (at 192.168.201.121@tcp) after server handle changed from 0xbd18ef06753d09bc to 0xbd18ef06753d0c7f [ 943.099387] Lustre: MGC192.168.201.121@tcp: Connection restored to 192.168.201.121@tcp (at 192.168.201.121@tcp) [ 944.167098] LustreError: lustre-MDT0000-mdc-ffff96cfc6a33800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 944.188773] Lustre: lustre-MDT0000-mdc-ffff96cfc6a33800: Connection restored to 192.168.201.121@tcp (at 192.168.201.121@tcp) [ 946.643048] Lustre: DEBUG MARKER: Wait for df (36569) ... [ 947.591379] Lustre: DEBUG MARKER: done [ 948.538824] Lustre: Unmounted lustre-client [ 970.032722] Key type lgssc unregistered [ 970.219598] LNet: 37579:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 970.228244] LNetError: 37579:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 971.304337] LNet: Removed LNI 192.168.201.21@tcp [ 971.796527] Key type .llcrypt unregistered [ 971.798045] Key type ._llcrypt unregistered [ 989.806130] Key type ._llcrypt registered [ 989.807905] Key type .llcrypt registered [ 990.558176] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 17:06:58 (1788901618) [ 991.960511] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 991.974572] alg: No test for adler32 (adler32-zlib) [ 992.890043] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 993.003629] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 994.615178] Key type lgssc registered [ 995.451994] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1020.897691] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1023.506352] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1033.511458] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1034.863755] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 1037.414706] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-09-0817h07m45s [ 1038.110136] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1041.600632] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1042.751748] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1062.879140] Lustre: 41710:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788901671/real 1788901671] req@0000000002639a2e x1875799306147456/t0(0) o101->lustre-MDT0000-mdc-ffff96cfcb90a800@192.168.201.121@tcp:12/10 lens 576/1152 e 0 to 1 dl 1788901691 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1062.902293] Lustre: lustre-MDT0000-mdc-ffff96cfcb90a800: Connection to lustre-MDT0000 (at 192.168.201.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1062.927622] Lustre: lustre-MDT0000-mdc-ffff96cfcb90a800: Connection restored to 192.168.201.121@tcp (at 192.168.201.121@tcp) [ 1083.657563] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1084.998571] Lustre: DEBUG MARKER: done [ 1086.187169] Lustre: Unmounted lustre-client [ 1105.489693] Key type lgssc unregistered [ 1105.654569] LNet: 42586:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1105.658242] LNetError: 42586:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1105.670490] LNet: Removed LNI 192.168.201.21@tcp [ 1106.054485] Key type .llcrypt unregistered [ 1106.056377] Key type ._llcrypt unregistered [ 1121.031894] Key type ._llcrypt registered [ 1121.033269] Key type .llcrypt registered [ 1121.605566] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 17:09:09 (1788901749) [ 1122.315570] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1123.171614] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 17:09:11 (1788901751) [ 1125.045825] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1125.066489] alg: No test for adler32 (adler32-zlib) [ 1126.028979] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 1126.156374] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 1127.767205] Key type lgssc registered [ 1128.393821] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1142.702300] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 17:09:30 (1788901770) [ 1157.270276] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1159.637956] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1168.481467] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1175.026855] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 1177.158662] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 1178.465704] Lustre: Unmounted lustre-client [ 1189.620880] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 1211.117674] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1213.930252] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1214.168307] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 1215.479042] Lustre: Unmounted lustre-client [ 1245.629439] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1248.432650] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1248.684146] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 1249.901164] Lustre: Unmounted lustre-client [ 1262.802371] Lustre: DEBUG MARKER: files compared the same [ 1277.359698] Key type lgssc unregistered [ 1277.517465] LNet: 50177:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1277.520763] LNetError: 50177:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1277.534489] LNet: Removed LNI 192.168.201.21@tcp [ 1277.865806] Key type .llcrypt unregistered [ 1277.867208] Key type ._llcrypt unregistered [ 1284.713610] Key type ._llcrypt registered [ 1284.715776] Key type .llcrypt registered [ 1285.401327] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 17:11:53 (1788901913) [ 1286.388544] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1286.442560] alg: No test for adler32 (adler32-zlib) [ 1287.352579] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 1287.474317] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 1289.079188] Key type lgssc registered [ 1289.626973] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1315.825504] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1318.850993] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1329.801231] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1332.080646] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 1337.829293] Lustre: Unmounted lustre-client [ 1349.408921] Key type lgssc unregistered [ 1349.570535] LNet: 53741:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1349.576448] LNetError: 53741:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1349.584875] LNet: Removed LNI 192.168.201.21@tcp [ 1349.993916] Key type .llcrypt unregistered [ 1349.995891] Key type ._llcrypt unregistered [ 1357.947410] Key type ._llcrypt registered [ 1357.948882] Key type .llcrypt registered [ 1358.670844] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 17:13:06 (1788901986) [ 1359.738099] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1359.765323] alg: No test for adler32 (adler32-zlib) [ 1360.705182] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 1360.896716] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 1362.519236] Key type lgssc registered [ 1363.140932] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1429.166560] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1445.153656] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1447.719796] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1467.987687] Key type lgssc unregistered [ 1468.130514] LNet: 57213:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1468.137124] LNetError: 57213:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1468.152319] LNet: Removed LNI 192.168.201.21@tcp [ 1468.483723] Key type .llcrypt unregistered [ 1468.485425] Key type ._llcrypt unregistered [ 1475.708856] Key type ._llcrypt registered [ 1475.709950] Key type .llcrypt registered [ 1476.365164] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 17:15:04 (1788902104) [ 1478.065042] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1478.102961] alg: No test for adler32 (adler32-zlib) [ 1478.994423] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 1479.116312] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 1480.711198] Key type lgssc registered [ 1481.443422] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1510.254940] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 1515.637524] Lustre: Unmounted lustre-client [ 1535.825551] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 17:16:04 (1788902164) [ 1575.183456] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1586.159386] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 1591.493343] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1591.496152] LustreError: 63654:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff96cfca9ad800: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 1591.503322] Lustre: 58195:0:(llite_lib.c:4342:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.201.121@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 1601.784114] Lustre: Unmounted lustre-client [ 1636.544359] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 17:17:44 (1788902264) [ 1640.385298] Key type lgssc unregistered [ 1640.508417] LNet: 64873:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1640.511374] LNetError: 64873:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1640.518812] LNet: Removed LNI 192.168.201.21@tcp [ 1640.774887] Key type .llcrypt unregistered [ 1640.776343] Key type ._llcrypt unregistered [ 1645.157876] Key type ._llcrypt registered [ 1645.159183] Key type .llcrypt registered [ 1645.326251] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1645.331456] alg: No test for adler32 (adler32-zlib) [ 1646.189953] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 1646.293383] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 1647.879166] Key type lgssc registered [ 1648.268473] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1665.637529] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1667.365927] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1673.763989] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1684.466409] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 1686.062547] Lustre: Unmounted lustre-client [ 1695.200370] Key type lgssc unregistered [ 1695.318473] LNet: 67867:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1695.320649] LNetError: 67867:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1695.328786] LNet: Removed LNI 192.168.201.21@tcp [ 1695.562468] Key type .llcrypt unregistered [ 1695.563516] Key type ._llcrypt unregistered [ 1699.055508] Key type ._llcrypt registered [ 1699.056532] Key type .llcrypt registered [ 1699.181918] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1699.186734] alg: No test for adler32 (adler32-zlib) [ 1700.042251] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 1700.149682] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 1701.735171] Key type lgssc registered [ 1702.166044] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1748.708246] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1750.578640] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1757.119095] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1767.412412] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 1767.834903] Lustre: Unmounted lustre-client [ 1784.912971] Key type lgssc unregistered [ 1785.028611] LNet: 71280:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1785.031258] LNetError: 71280:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1785.039132] LNet: Removed LNI 192.168.201.21@tcp [ 1785.284667] Key type .llcrypt unregistered [ 1785.285981] Key type ._llcrypt unregistered [ 1790.142530] Key type ._llcrypt registered [ 1790.143545] Key type .llcrypt registered [ 1790.578484] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 17:20:18 (1788902418) [ 1791.310758] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1791.333413] alg: No test for adler32 (adler32-zlib) [ 1792.182770] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 1792.267513] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 1793.847227] Key type lgssc registered [ 1794.200039] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1811.621342] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1813.316769] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1819.817161] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1830.384285] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 1832.609869] Lustre: Unmounted lustre-client [ 1832.722365] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 1833.323567] Lustre: Unmounted lustre-client [ 1850.620730] Key type lgssc unregistered [ 1850.742538] LNet: 74948:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1850.746818] LNetError: 74948:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1850.757571] LNet: Removed LNI 192.168.201.21@tcp [ 1851.019379] Key type .llcrypt unregistered [ 1851.020577] Key type ._llcrypt unregistered [ 1855.074450] Key type ._llcrypt registered [ 1855.075455] Key type .llcrypt registered [ 1855.331733] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1855.336654] alg: No test for adler32 (adler32-zlib) [ 1856.205675] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 1856.302416] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 1857.887136] Key type lgssc registered [ 1858.228783] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1875.752882] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1877.429260] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1883.637544] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1894.384833] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 1896.115910] Lustre: Unmounted lustre-client [ 1905.424449] Key type lgssc unregistered [ 1905.534677] LNet: 77962:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1905.537577] LNetError: 77962:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1905.546876] LNet: Removed LNI 192.168.201.21@tcp [ 1905.787954] Key type .llcrypt unregistered [ 1905.790174] Key type ._llcrypt unregistered [ 1911.583721] Key type ._llcrypt registered [ 1911.584780] Key type .llcrypt registered [ 1912.171234] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 17:22:20 (1788902540) [ 1912.987141] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1913.020381] alg: No test for adler32 (adler32-zlib) [ 1913.872187] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 1913.958867] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 1915.535254] Key type lgssc registered [ 1916.060803] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1934.727279] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1936.603157] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1944.334781] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1954.802115] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 1959.409275] Lustre: lustre: root_squash is set to 0:0 [ 1959.416539] Lustre: lustre: nosquash_nids is cleared [ 1968.121702] Lustre: lustre: root_squash is set to 500:500 [ 1976.819775] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.201.21@tcp 192.168.0.[2,10]@tcp [ 1988.091402] Lustre: lustre: nosquash_nids set to 192.168.201.21/24@tcp [ 1989.539159] Lustre: Unmounted lustre-client [ 2008.468329] Key type lgssc unregistered [ 2008.630763] LNet: 81971:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2008.634487] LNetError: 81971:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2008.642105] LNet: Removed LNI 192.168.201.21@tcp [ 2008.979808] Key type .llcrypt unregistered [ 2008.981639] Key type ._llcrypt unregistered [ 2014.575822] Key type ._llcrypt registered [ 2014.576768] Key type .llcrypt registered [ 2015.165114] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 17:24:03 (1788902643) [ 2016.350780] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 2017.226581] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 17:24:05 (1788902645) [ 2018.125193] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2018.198490] alg: No test for adler32 (adler32-zlib) [ 2019.057821] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 2019.177256] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 2020.775275] Key type lgssc registered [ 2021.273486] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2043.490421] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2045.938787] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2054.474897] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2054.701253] Lustre: lustre: root_squash is set to 500:500 [ 2054.714413] Lustre: lustre: nosquash_nids set to 192.168.201.21/24@tcp [ 2060.768139] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 2067.192175] Lustre: Unmounted lustre-client [ 2081.761099] Key type lgssc unregistered [ 2081.871639] LNet: 85912:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2081.874104] LNetError: 85912:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2081.883761] LNet: Removed LNI 192.168.201.21@tcp [ 2082.112985] Key type .llcrypt unregistered [ 2082.113865] Key type ._llcrypt unregistered [ 2087.670093] Key type ._llcrypt registered [ 2087.671108] Key type .llcrypt registered [ 2088.296328] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 2089.100418] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 17:25:17 (1788902717) [ 2096.555412] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2096.568175] alg: No test for adler32 (adler32-zlib) [ 2097.460672] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 2097.575214] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 2099.175160] Key type lgssc registered [ 2099.797584] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2134.905740] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2137.287684] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2146.872840] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2175.977025] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2178.408453] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2185.884100] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2196.962402] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 2220.695652] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff96cfc63cd000.ost_server_uuid 50 [ 2223.447807] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff96cfc63cd000.ost_server_uuid in FULL state after 0 sec [ 2224.159709] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 2230.099129] Lustre: Unmounted lustre-client [ 2231.828097] Lustre: Unmounted lustre-client [ 2281.772062] Key type lgssc unregistered [ 2282.171672] LNet: 93545:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2282.182341] LNetError: 93545:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2282.222686] LNet: Removed LNI 192.168.201.21@tcp [ 2283.269654] Key type .llcrypt unregistered [ 2283.271649] Key type ._llcrypt unregistered [ 2334.017689] Key type ._llcrypt registered [ 2334.019939] Key type .llcrypt registered [ 2335.757121] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 17:29:22 (1788902962) [ 2361.086034] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2361.104810] alg: No test for adler32 (adler32-zlib) [ 2362.312412] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 2362.619550] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 2364.343221] Key type lgssc registered [ 2365.944932] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2450.383886] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2456.600628] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2480.240587] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2483.166827] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 2504.671443] Lustre: 94907:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788903117/real 1788903117] req@00000000b6c8a506 x1875800742696192/t0(0) o400->lustre-OST0000-osc-ffff96cfc54f7000@192.168.201.121@tcp:28/4 lens 224/224 e 0 to 1 dl 1788903133 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2504.713044] Lustre: lustre-OST0000-osc-ffff96cfc54f7000: Connection to lustre-OST0000 (at 192.168.201.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2509.408387] Lustre: 94908:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788903122/real 1788903122] req@00000000e0003e5a x1875800742696704/t0(0) o400->lustre-OST0000-osc-ffff96cfc54f7000@192.168.201.121@tcp:28/4 lens 224/224 e 0 to 1 dl 1788903138 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2514.913109] Lustre: 94907:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788903127/real 1788903127] req@0000000012d80f51 x1875800742697216/t0(0) o400->lustre-OST0000-osc-ffff96cfc54f7000@192.168.201.121@tcp:28/4 lens 224/224 e 0 to 1 dl 1788903143 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2514.991099] Lustre: lustre-OST0000-osc-ffff96cfc54f7000: Connection restored to 192.168.201.121@tcp (at 192.168.201.121@tcp) [ 2520.036829] Lustre: lustre-MDT0000-mdc-ffff96cfc54f7000: Connection to lustre-MDT0000 (at 192.168.201.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2521.055200] Lustre: 94908:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788903132/real 1788903132] req@000000007c1d49cf x1875800742697728/t0(0) o400->lustre-OST0000-osc-ffff96cfc54f7000@192.168.201.121@tcp:28/4 lens 224/224 e 0 to 1 dl 1788903148 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2535.394554] Lustre: 94908:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788903148/real 1788903148] req@000000009d39afc0 x1875800742700032/t0(0) o400->MGC192.168.201.121@tcp@192.168.201.121@tcp:26/25 lens 224/224 e 0 to 1 dl 1788903164 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2535.419332] LustreError: MGC192.168.201.121@tcp: Connection to MGS (at 192.168.201.121@tcp) was lost; in progress operations using this service will fail [ 2545.647156] Lustre: Evicted from MGS (at 192.168.201.121@tcp) after server handle changed from 0xb35ea4671fcf589 to 0xb35ea4671fcf822 [ 2545.669091] Lustre: MGC192.168.201.121@tcp: Connection restored to 192.168.201.121@tcp (at 192.168.201.121@tcp) [ 2549.889120] Lustre: lustre-MDT0000-mdc-ffff96cfc54f7000: Connection restored to 192.168.201.121@tcp (at 192.168.201.121@tcp) [ 2557.060408] Lustre: Unmounted lustre-client [ 2588.446546] Key type lgssc unregistered [ 2588.860408] LNet: 99212:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2588.888716] LNetError: 99212:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2588.909641] LNet: Removed LNI 192.168.201.21@tcp [ 2589.859470] Key type .llcrypt unregistered [ 2589.862077] Key type ._llcrypt unregistered [ 2610.435645] Key type ._llcrypt registered [ 2610.442548] Key type .llcrypt registered [ 2612.279783] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 17:33:59 (1788903239) [ 2628.797877] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2628.884897] alg: No test for adler32 (adler32-zlib) [ 2630.339041] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 2630.959616] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 2632.687168] Key type lgssc registered [ 2634.621226] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2682.448222] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2791.874825] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2796.986326] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2819.485814] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2820.997243] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 2822.858675] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 2824.272357] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 17:37:31 (1788903451) [ 2944.426872] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2954.676440] Lustre: Unmounted lustre-client [ 3010.366870] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3012.229059] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3021.454360] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3039.396454] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 17:41:07 (1788903667) [ 3073.207524] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3075.279387] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3085.074217] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3090.419564] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 3091.709623] Lustre: Unmounted lustre-client [ 3118.467321] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 17:42:26 (1788903746) [ 3151.582971] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3153.559609] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3162.333905] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3162.497896] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 3163.631205] Lustre: Unmounted lustre-client [ 3181.165704] Key type lgssc unregistered [ 3181.283670] LNet: 122160:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3181.287548] LNetError: 122160:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3181.295909] LNet: Removed LNI 192.168.201.21@tcp [ 3181.563047] Key type .llcrypt unregistered [ 3181.564748] Key type ._llcrypt unregistered [ 3186.873729] Key type ._llcrypt registered [ 3186.875175] Key type .llcrypt registered [ 3187.367534] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 17:43:35 (1788903815) [ 3188.090522] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3188.097617] alg: No test for adler32 (adler32-zlib) [ 3188.968790] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 3189.060991] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 3190.655635] Key type lgssc registered [ 3191.066403] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3209.499606] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3211.135668] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3217.522660] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3228.147517] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 3238.828163] Lustre: Unmounted lustre-client [ 3256.057258] Key type lgssc unregistered [ 3256.172519] LNet: 125734:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3256.175958] LNetError: 125734:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3256.184801] LNet: Removed LNI 192.168.201.21@tcp [ 3256.442085] Key type .llcrypt unregistered [ 3256.443898] Key type ._llcrypt unregistered [ 3261.689180] Key type ._llcrypt registered [ 3261.690475] Key type .llcrypt registered [ 3262.155860] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 17:44:50 (1788903890) [ 3262.956839] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3263.028152] alg: No test for adler32 (adler32-zlib) [ 3263.931401] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 3264.035092] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 3265.631150] Key type lgssc registered [ 3266.017699] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3283.908596] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3285.747407] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3292.541292] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3302.913664] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 3308.002146] Lustre: lustre-OST0000-osc-ffff96cfc4f51800: Connection to lustre-OST0000 (at 192.168.201.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3313.374521] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff96cfc4f51800.ost_server_uuid 50 [ 3313.860384] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 3323.195326] Lustre: Unmounted lustre-client [ 3333.710234] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 17:46:02 (1788903962) [ 3343.383190] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3345.049698] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3351.309066] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3359.300947] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3359.443573] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 3364.834284] Lustre: lustre-OST0000-osc-ffff96cfc58c2000: Connection to lustre-OST0000 (at 192.168.201.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3378.796222] Lustre: Unmounted lustre-client [ 3401.862443] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 17:47:10 (1788904030) [ 3412.284917] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3413.970954] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3420.440891] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3426.677405] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3426.829494] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 3431.906949] Lustre: lustre-OST0000-osc-ffff96cfc3ef2000: Connection to lustre-OST0000 (at 192.168.201.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3451.620063] Lustre: Unmounted lustre-client [ 3474.501541] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 17:48:22 (1788904102) [ 3503.292349] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3504.885568] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3512.141400] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3582.071394] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3587.280731] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3608.898829] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3665.704340] LustreError: 126685:0:(lmv_obd.c:211:lmv_notify()) activation of lustre-MDT0001_UUID failed: -22 [ 3665.791803] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 3711.573791] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3713.445774] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 3715.706887] Lustre: Unmounted lustre-client [ 3747.674614] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 17:52:54 (1788904374) [ 3783.800907] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3790.066884] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3810.983817] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3844.094948] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3885.947711] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 3932.704476] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3934.520725] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 3937.316753] Lustre: lustre-OST0001-osc-ffff96cfc3ef7800: Connection to lustre-OST0001 (at 192.168.201.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3955.738732] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3966.079818] Lustre: Unmounted lustre-client [ 4018.072916] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 17:57:25 (1788904645) [ 4052.794052] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4059.635742] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4089.722665] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4090.519204] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 4112.209505] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4113.758979] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 4126.529402] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff96cfc9c5a800.ost_server_uuid 50 [ 4127.518862] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff96cfc9c5a800.ost_server_uuid in FULL state after 0 sec [ 4129.611467] Lustre: Unmounted lustre-client [ 4129.799565] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 4129.804831] Lustre: Skipped 3 previous similar messages [ 4129.882267] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 4130.671909] LustreError: 149106:0:(sec.c:403:import_sec_validate_get()) import 00000000fee392be (NEW) with no sec [ 4172.048983] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 18:00:00 (1788904800) [ 4189.679873] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4192.919908] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4205.281816] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4217.027607] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4217.325920] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 4217.399803] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 4229.703281] Lustre: Unmounted lustre-client [ 4229.704937] Lustre: Skipped 1 previous similar message [ 4268.203616] Key type lgssc unregistered [ 4268.370125] LNet: 152533:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4268.374262] LNetError: 152533:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4268.385518] LNet: Removed LNI 192.168.201.21@tcp [ 4268.827282] Key type .llcrypt unregistered [ 4268.828980] Key type ._llcrypt unregistered [ 4277.757204] Key type ._llcrypt registered [ 4277.758616] Key type .llcrypt registered [ 4278.656383] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 18:01:46 (1788904906) [ 4279.248766] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4279.267128] alg: No test for adler32 (adler32-zlib) [ 4280.164910] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 4280.270546] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 4281.871382] Key type lgssc registered [ 4282.589693] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4310.029746] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4312.726022] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4322.636967] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4332.442296] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4333.725860] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 4334.809943] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 4337.655371] LustreError: lustre-MDT0001-mdc-ffff96cfc6a34800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 4337.666621] Lustre: lustre-MDT0001-mdc-ffff96cfc6a34800: Connection restored to 192.168.201.121@tcp (at 192.168.201.121@tcp) [ 4341.291693] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4342.075283] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 4355.565793] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 4362.002330] Lustre: Unmounted lustre-client [ 4381.926301] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 18:03:30 (1788905010) [ 4413.581619] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4415.359692] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4423.826951] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4423.997527] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 4434.351301] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4434.848992] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 4450.274872] Lustre: lustre-OST0001-osc-ffff96cfc3ef2800: Connection to lustre-OST0001 (at 192.168.201.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4452.329851] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4462.583303] Lustre: Unmounted lustre-client [ 4486.107228] Key type lgssc unregistered [ 4486.220767] LNet: 162110:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4486.223237] LNetError: 162110:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4486.235808] LNet: Removed LNI 192.168.201.21@tcp [ 4486.525569] Key type .llcrypt unregistered [ 4486.526854] Key type ._llcrypt unregistered [ 4496.751174] Key type ._llcrypt registered [ 4496.752493] Key type .llcrypt registered [ 4497.201105] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 18:05:25 (1788905125) [ 4497.963111] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4497.978856] alg: No test for adler32 (adler32-zlib) [ 4498.826366] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 4498.912098] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 4500.495209] Key type lgssc registered [ 4500.863931] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4518.096250] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4519.861503] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4526.387537] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4527.563569] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 4563.795114] Lustre: Unmounted lustre-client [ 4580.632955] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4580.783427] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 4613.005717] Lustre: Unmounted lustre-client [ 4630.300971] Key type lgssc unregistered [ 4630.423565] LNet: 166533:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4630.426559] LNetError: 166533:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4630.436689] LNet: Removed LNI 192.168.201.21@tcp [ 4630.690684] Key type .llcrypt unregistered [ 4630.691757] Key type ._llcrypt unregistered [ 4635.611415] Key type ._llcrypt registered [ 4635.612312] Key type .llcrypt registered [ 4636.061111] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 18:07:44 (1788905264) [ 4636.802713] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4636.812205] alg: No test for adler32 (adler32-zlib) [ 4637.678547] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 4637.769593] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 4639.359148] Key type lgssc registered [ 4639.770349] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4658.022677] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4659.803475] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4666.475608] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4677.104038] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 4683.490143] Lustre: Unmounted lustre-client [ 4708.871683] Key type lgssc unregistered [ 4708.989481] LNet: 170267:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4708.992926] LNetError: 170267:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4709.000625] LNet: Removed LNI 192.168.201.21@tcp [ 4709.265946] Key type .llcrypt unregistered [ 4709.266849] Key type ._llcrypt unregistered [ 4713.631394] Key type ._llcrypt registered [ 4713.634662] Key type .llcrypt registered [ 4713.802080] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4713.816614] alg: No test for adler32 (adler32-zlib) [ 4714.679506] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 4714.778445] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 4716.367123] Key type lgssc registered [ 4716.735769] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4734.296869] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4736.140251] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4742.622621] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4753.390871] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 4756.226148] Lustre: Unmounted lustre-client [ 4773.095406] Key type lgssc unregistered [ 4773.209537] LNet: 173314:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4773.211844] LNetError: 173314:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4773.219707] LNet: Removed LNI 192.168.201.21@tcp [ 4773.465951] Key type .llcrypt unregistered [ 4773.467600] Key type ._llcrypt unregistered [ 4778.271754] Key type ._llcrypt registered [ 4778.273014] Key type .llcrypt registered [ 4778.737258] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 18:10:07 (1788905407) [ 4779.420332] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4779.443382] alg: No test for adler32 (adler32-zlib) [ 4780.288052] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 4780.369847] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 4781.951176] Key type lgssc registered [ 4782.303077] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4799.157705] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4800.930921] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4807.342737] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4817.904710] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 4824.108846] Lustre: Unmounted lustre-client [ 4841.104925] Key type lgssc unregistered [ 4841.230391] LNet: 177046:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4841.233360] LNetError: 177046:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4841.248104] LNet: Removed LNI 192.168.201.21@tcp [ 4841.516054] Key type .llcrypt unregistered [ 4841.517415] Key type ._llcrypt unregistered [ 4845.679778] Key type ._llcrypt registered [ 4845.681271] Key type .llcrypt registered [ 4845.861133] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4845.867081] alg: No test for adler32 (adler32-zlib) [ 4846.737614] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 4846.840725] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 4848.431194] Key type lgssc registered [ 4848.804870] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4865.412498] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4867.143261] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4873.543144] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4883.952769] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 4886.787581] Lustre: Unmounted lustre-client [ 4903.631632] Key type lgssc unregistered [ 4903.751543] LNet: 180090:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4903.753574] LNetError: 180090:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4903.761661] LNet: Removed LNI 192.168.201.21@tcp [ 4904.019036] Key type .llcrypt unregistered [ 4904.020222] Key type ._llcrypt unregistered [ 4908.811342] Key type ._llcrypt registered [ 4908.812340] Key type .llcrypt registered [ 4909.222525] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 18:12:17 (1788905537) [ 4915.650598] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4915.659466] alg: No test for adler32 (adler32-zlib) [ 4916.511536] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 4916.597694] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 4918.183134] Key type lgssc registered [ 4918.527586] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4949.281736] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4952.390906] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4965.339697] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5001.958262] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 18:13:49 (1788905629) [ 5020.303411] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5023.065988] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5031.303715] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5033.555465] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 5044.429462] Lustre: Unmounted lustre-client [ 5054.684788] Key type lgssc unregistered [ 5054.810637] LNet: 187158:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5054.812916] LNetError: 187158:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5054.820789] LNet: Removed LNI 192.168.201.21@tcp [ 5055.105075] Key type .llcrypt unregistered [ 5055.106860] Key type ._llcrypt unregistered [ 5061.432863] Key type ._llcrypt registered [ 5061.434062] Key type .llcrypt registered [ 5062.053102] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 5062.735958] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 18:14:50 (1788905690) [ 5069.749612] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5069.786913] alg: No test for adler32 (adler32-zlib) [ 5070.681340] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 5070.801128] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 5072.391139] Key type lgssc registered [ 5072.805678] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5112.592369] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5114.887683] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5124.291034] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5132.560584] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5133.754483] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 5141.303804] Lustre: Unmounted lustre-client [ 5211.996616] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5214.563516] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5224.581307] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5265.682449] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 18:18:13 (1788905893) [ 5267.153612] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 5269.029428] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 18:18:16 (1788905896) [ 5396.324397] Key type lgssc unregistered [ 5396.533069] LNet: 198576:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5396.538748] LNetError: 198576:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5396.549764] LNet: Removed LNI 192.168.201.21@tcp [ 5397.234594] Key type .llcrypt unregistered [ 5397.236799] Key type ._llcrypt unregistered [ 5412.309830] Key type ._llcrypt registered [ 5412.314213] Key type .llcrypt registered [ 5413.903761] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 18:20:41 (1788906041) [ 5457.537292] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5457.674668] alg: No test for adler32 (adler32-zlib) [ 5458.741541] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 5458.978239] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 5460.655212] Key type lgssc registered [ 5461.970633] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5509.321346] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5514.829947] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5549.628386] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5576.804741] Key type lgssc unregistered [ 5577.098988] LNet: 202306:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5577.107732] LNetError: 202306:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5577.121048] LNet: Removed LNI 192.168.201.21@tcp [ 5577.912898] Key type .llcrypt unregistered [ 5577.914952] Key type ._llcrypt unregistered [ 5594.325943] Key type ._llcrypt registered [ 5594.327733] Key type .llcrypt registered [ 5595.724794] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 18:23:43 (1788906223) [ 5598.944692] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5598.956503] alg: No test for adler32 (adler32-zlib) [ 5600.140855] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 5600.491996] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 5602.215209] Key type lgssc registered [ 5603.586839] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5660.266365] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5666.357093] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5691.368969] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5702.756231] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 5712.883928] Lustre: lustre-MDT0000-mdc-ffff96cfc7628800: Connection to lustre-MDT0000 (at 192.168.201.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5723.120089] Lustre: lustre-MDT0001-mdc-ffff96cfc7628800: Connection to lustre-MDT0001 (at 192.168.201.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5733.343342] Lustre: 203296:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788906346/real 1788906346] req@00000000eec82eb0 x1875804138062464/t0(0) o400->MGC192.168.201.121@tcp@192.168.201.121@tcp:26/25 lens 224/224 e 0 to 1 dl 1788906362 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 5733.389233] LustreError: MGC192.168.201.121@tcp: Connection to MGS (at 192.168.201.121@tcp) was lost; in progress operations using this service will fail [ 5758.956114] Lustre: Evicted from MGS (at 192.168.201.121@tcp) after server handle changed from 0x61d6ce830fa6b13 to 0x61d6ce830fa8679 [ 5758.979654] Lustre: MGC192.168.201.121@tcp: Connection restored to 192.168.201.121@tcp (at 192.168.201.121@tcp) [ 5788.660399] LustreError: lustre-MDT0000-mdc-ffff96cfc7628800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 5788.742467] Lustre: lustre-MDT0000-mdc-ffff96cfc7628800: Connection restored to 192.168.201.121@tcp (at 192.168.201.121@tcp) [ 5791.998654] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5793.769712] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 5801.455379] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5803.906912] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 5809.738335] Lustre: Unmounted lustre-client [ 5849.965109] Key type lgssc unregistered [ 5850.403817] LNet: 207257:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5850.409336] LNetError: 207257:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5850.451892] LNet: Removed LNI 192.168.201.21@tcp [ 5851.531394] Key type .llcrypt unregistered [ 5851.537628] Key type ._llcrypt unregistered [ 5870.741455] Key type ._llcrypt registered [ 5870.745963] Key type .llcrypt registered [ 5872.116686] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 18:28:19 (1788906499) [ 5875.321392] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5875.459616] alg: No test for adler32 (adler32-zlib) [ 5876.818950] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 5877.200969] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 5878.927851] Key type lgssc registered [ 5880.700576] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5936.351669] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5942.400596] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6001.894456] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6008.000273] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6045.495566] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6070.230809] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6118.405451] Key type lgssc unregistered [ 6118.675847] LNet: 212551:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6118.684199] LNetError: 212551:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6118.706756] LNet: Removed LNI 192.168.201.21@tcp [ 6119.446725] Key type .llcrypt unregistered [ 6119.450717] Key type ._llcrypt unregistered [ 6163.808953] Key type ._llcrypt registered [ 6163.811537] Key type .llcrypt registered [ 6165.240497] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 18:33:12 (1788906792) [ 6203.389163] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6203.471488] alg: No test for adler32 (adler32-zlib) [ 6204.617327] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 6205.025895] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 6206.735265] Key type lgssc registered [ 6208.228361] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6284.802275] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6290.204754] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6309.642642] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6354.390703] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 18:36:21 (1788906981) [ 6361.290194] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 18:36:28 (1788906988) [ 6391.072485] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6396.114315] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6414.146768] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6416.708526] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 6421.371309] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [ 6422.619242] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 6423.729547] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 6425.055802] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [ 6427.113508] Lustre: lustre-MDT0000-mdc-ffff96cfcb93c800: Connection to lustre-MDT0000 (at 192.168.201.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6442.463150] Lustre: 214446:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788907055/real 1788907055] req@00000000d17a4728 x1875804771329024/t0(0) o400->MGC192.168.201.121@tcp@192.168.201.121@tcp:26/25 lens 224/224 e 0 to 1 dl 1788907071 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 6442.496237] LustreError: MGC192.168.201.121@tcp: Connection to MGS (at 192.168.201.121@tcp) was lost; in progress operations using this service will fail [ 6452.710172] Lustre: Evicted from MGS (at 192.168.201.121@tcp) after server handle changed from 0xf1f7358b5a13c5d2 to 0xf1f7358b5a13c745 [ 6452.722935] Lustre: MGC192.168.201.121@tcp: Connection restored to 192.168.201.121@tcp (at 192.168.201.121@tcp) [ 6460.040487] Lustre: lustre-MDT0000-mdc-ffff96cfcb93c800: Connection restored to 192.168.201.121@tcp (at 192.168.201.121@tcp) [ 6467.340313] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6468.628447] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6471.715738] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [ 6478.318340] Lustre: lustre-MDT0000-mdc-ffff96cfcb93c800: Connection to lustre-MDT0000 (at 192.168.201.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6493.673264] LustreError: MGC192.168.201.121@tcp: Connection to MGS (at 192.168.201.121@tcp) was lost; in progress operations using this service will fail [ 6493.703362] Lustre: Evicted from MGS (at 192.168.201.121@tcp) after server handle changed from 0xf1f7358b5a13c745 to 0xf1f7358b5a13cb51 [ 6493.715432] Lustre: MGC192.168.201.121@tcp: Connection restored to 192.168.201.121@tcp (at 192.168.201.121@tcp) [ 6495.867587] Lustre: lustre-MDT0000-mdc-ffff96cfcb93c800: Connection restored to 192.168.201.121@tcp (at 192.168.201.121@tcp) [ 6503.077466] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6504.453396] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6508.225606] Lustre: Unmounted lustre-client [ 6573.144997] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6578.402440] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6596.525987] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6597.130160] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 6598.959442] Lustre: Unmounted lustre-client [ 6624.433775] Key type lgssc unregistered [ 6624.687118] LNet: 225019:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6624.691307] LNetError: 225019:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6624.704442] LNet: Removed LNI 192.168.201.21@tcp [ 6625.324964] Key type .llcrypt unregistered [ 6625.326097] Key type ._llcrypt unregistered [ 6640.661397] Key type ._llcrypt registered [ 6640.663298] Key type .llcrypt registered [ 6641.775749] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 18:41:09 (1788907269) [ 6645.820400] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6645.829933] alg: No test for adler32 (adler32-zlib) [ 6647.126501] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 6647.553314] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 6649.353777] Key type lgssc registered [ 6650.947509] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6712.784842] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6718.548848] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6739.011749] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6740.803369] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 6750.657435] Lustre: Unmounted lustre-client [ 6820.423768] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6825.736470] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6845.948618] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6846.608585] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 6855.778916] Lustre: Unmounted lustre-client [ 6924.315796] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6930.690925] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6952.564242] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6953.259309] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 6955.616591] Lustre: Unmounted lustre-client [ 6992.956449] Key type lgssc unregistered [ 6993.240427] LNet: 232069:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6993.249613] LNetError: 232069:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6993.267609] LNet: Removed LNI 192.168.201.21@tcp [ 6994.011367] Key type .llcrypt unregistered [ 6994.013957] Key type ._llcrypt unregistered [ 7012.176391] Key type ._llcrypt registered [ 7012.177784] Key type .llcrypt registered [ 7013.502844] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 18:47:20 (1788907640) [ 7016.812521] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7016.940303] alg: No test for adler32 (adler32-zlib) [ 7018.063921] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 7018.474919] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 7020.247163] Key type lgssc registered [ 7021.528115] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7064.230732] Key type lgssc unregistered [ 7064.440767] LNet: 234385:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7064.457569] LNetError: 234385:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7064.484824] LNet: Removed LNI 192.168.201.21@tcp [ 7065.162899] Key type .llcrypt unregistered [ 7065.166430] Key type ._llcrypt unregistered [ 7091.732941] Key type ._llcrypt registered [ 7091.745272] Key type .llcrypt registered [ 7092.215132] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7092.226738] alg: No test for adler32 (adler32-zlib) [ 7093.332659] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 7093.547049] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 7095.271426] Key type lgssc registered [ 7096.519290] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7166.152683] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7170.339359] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7190.684349] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7237.832558] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [ 7239.214398] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 18:51:06 (1788907866) [ 7269.284143] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7274.662311] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7294.328977] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7314.008755] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7315.692205] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 7321.074324] Lustre: lustre-OST0001-osc-ffff96cfc558e000: Connection to lustre-OST0001 (at 192.168.201.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7326.910633] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7337.243525] Lustre: Unmounted lustre-client [ 7377.030597] Key type lgssc unregistered [ 7377.226557] LNet: 241489:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7377.234697] LNetError: 241489:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7377.245512] LNet: Removed LNI 192.168.201.21@tcp [ 7377.752389] Key type .llcrypt unregistered [ 7377.755338] Key type ._llcrypt unregistered [ 7402.581859] Key type ._llcrypt registered [ 7402.583538] Key type .llcrypt registered [ 7403.748441] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 18:53:51 (1788908031) [ 7415.747633] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7415.757805] alg: No test for adler32 (adler32-zlib) [ 7416.822924] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 7417.054589] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 7418.711186] Key type lgssc registered [ 7419.798135] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7459.756481] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7463.573423] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7481.074920] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 18:55:08 (1788908108) [ 7504.105379] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7508.479760] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7523.632481] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7526.095705] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 7533.434723] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 7540.008779] Lustre: Unmounted lustre-client [ 7616.900210] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7621.094280] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7670.632379] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7671.266859] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 7673.166428] Lustre: Unmounted lustre-client [ 7696.810932] Key type lgssc unregistered [ 7697.030329] LNet: 249205:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7697.034949] LNetError: 249205:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7697.055131] LNet: Removed LNI 192.168.201.21@tcp [ 7697.574992] Key type .llcrypt unregistered [ 7697.578158] Key type ._llcrypt unregistered [ 7717.486302] Key type ._llcrypt registered [ 7717.515051] Key type .llcrypt registered [ 7717.781429] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7717.788550] alg: No test for adler32 (adler32-zlib) [ 7718.756533] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 7718.925654] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 7720.559198] Key type lgssc registered [ 7721.397955] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7779.111497] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7783.081886] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7799.148421] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7830.625865] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [ 7831.976599] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 19:00:59 (1788908459) [ 7852.570522] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7856.319169] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7870.127767] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7873.371379] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 7875.011649] Lustre: Unmounted lustre-client [ 7895.243781] Key type lgssc unregistered [ 7895.372683] LNet: 255836:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7895.377897] LNetError: 255836:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7895.389323] LNet: Removed LNI 192.168.201.21@tcp [ 7895.671553] Key type .llcrypt unregistered [ 7895.673481] Key type ._llcrypt unregistered [ 7903.082461] Key type ._llcrypt registered [ 7903.084741] Key type .llcrypt registered [ 7903.828528] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [ 7904.693817] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 19:02:12 (1788908532) [ 7909.476302] Key type .llcrypt unregistered [ 7909.477596] Key type ._llcrypt unregistered [ 7914.168049] Key type ._llcrypt registered [ 7914.170797] Key type .llcrypt registered [ 7914.400248] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7914.416179] alg: No test for adler32 (adler32-zlib) [ 7915.357504] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 7915.479090] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 7917.087180] Key type lgssc registered [ 7917.746486] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7960.701531] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7985.275385] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 7987.502961] Lustre: Unmounted lustre-client [ 8008.481487] Key type lgssc unregistered [ 8008.740905] LNet: 259281:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8008.750774] LNetError: 259281:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8008.781923] LNet: Removed LNI 192.168.201.21@tcp [ 8009.598098] Key type .llcrypt unregistered [ 8009.599753] Key type ._llcrypt unregistered [ 8026.651755] Key type ._llcrypt registered [ 8026.655170] Key type .llcrypt registered [ 8027.959835] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 19:04:15 (1788908655) [ 8030.794908] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8030.828031] alg: No test for adler32 (adler32-zlib) [ 8031.992576] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 8032.224500] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 8033.967248] Key type lgssc registered [ 8035.203460] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8116.039592] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8144.194955] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8148.449319] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8150.989499] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 8153.092747] Lustre: Unmounted lustre-client [ 8177.743633] Key type lgssc unregistered [ 8177.919223] LNet: 262816:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8177.921797] LNetError: 262816:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8177.929873] LNet: Removed LNI 192.168.201.21@tcp [ 8178.432480] Key type .llcrypt unregistered [ 8178.433886] Key type ._llcrypt unregistered [ 8189.758806] Key type ._llcrypt registered [ 8189.769368] Key type .llcrypt registered [ 8190.731355] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 19:06:58 (1788908818) [ 8192.644734] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8192.656712] alg: No test for adler32 (adler32-zlib) [ 8193.658705] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 8193.866890] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 8195.536576] Key type lgssc registered [ 8196.508965] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8235.327569] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8240.385997] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8257.974910] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8264.728887] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 8269.799158] Lustre: lustre-MDT0000-mdc-ffff96cfc4b94800: Connection to lustre-MDT0000 (at 192.168.201.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8274.636956] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 8274.669705] LustreError: 265973:0:(file.c:6167:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [ 8275.136967] LustreError: MGC192.168.201.121@tcp: Connection to MGS (at 192.168.201.121@tcp) was lost; in progress operations using this service will fail [ 8275.184741] Lustre: Unmounted lustre-client [ 8293.416403] Key type lgssc unregistered [ 8293.613774] LNet: 266384:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8293.619436] LNetError: 266384:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8293.635757] LNet: Removed LNI 192.168.201.21@tcp [ 8294.226402] Key type .llcrypt unregistered [ 8294.229606] Key type ._llcrypt unregistered [ 8306.670711] Key type ._llcrypt registered [ 8306.674449] Key type .llcrypt registered [ 8307.663681] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 19:08:55 (1788908935) [ 8309.541556] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8309.567646] alg: No test for adler32 (adler32-zlib) [ 8310.698952] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 8310.971058] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 8312.631540] Key type lgssc registered [ 8313.420701] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8348.952477] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8353.038919] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8368.876192] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8379.912913] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 8385.001210] Lustre: lustre-MDT0001-mdc-ffff96cfc7395000: Connection to lustre-MDT0001 (at 192.168.201.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8389.831768] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 8391.839853] Lustre: Unmounted lustre-client [ 8408.880554] Key type lgssc unregistered [ 8409.064758] LNet: 269960:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8409.071991] LNetError: 269960:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8409.090956] LNet: Removed LNI 192.168.201.21@tcp [ 8409.659905] Key type .llcrypt unregistered [ 8409.664592] Key type ._llcrypt unregistered [ 8422.286338] Key type ._llcrypt registered [ 8422.289540] Key type .llcrypt registered [ 8423.203686] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 19:10:50 (1788909050) [ 8434.856734] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8434.867542] alg: No test for adler32 (adler32-zlib) [ 8435.856423] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 8435.998340] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 8437.607208] Key type lgssc registered [ 8438.344046] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8506.097778] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8507.512086] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 8535.534187] Lustre: lustre-MDT0001-mdc-ffff96cfcb90a000: Connection to lustre-MDT0001 (at 192.168.201.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8543.513897] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 8553.790812] Lustre: Unmounted lustre-client [ 8592.255916] Key type lgssc unregistered [ 8592.410950] LNet: 275737:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8592.417326] LNetError: 275737:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8592.438911] LNet: Removed LNI 192.168.201.21@tcp [ 8592.918888] Key type .llcrypt unregistered [ 8592.920777] Key type ._llcrypt unregistered [ 8603.782659] Key type ._llcrypt registered [ 8603.784724] Key type .llcrypt registered [ 8604.662693] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 19:13:52 (1788909232) [ 8605.799707] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [ 8607.077502] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 19:13:54 (1788909234) [ 8608.247554] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [ 8609.463331] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 19:13:57 (1788909237) [ 8610.610537] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [ 8611.891677] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 19:13:59 (1788909239) [ 8612.952436] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [ 8614.159677] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 19:14:02 (1788909242) [ 8615.327754] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [ 8616.509849] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 19:14:04 (1788909244) [ 8622.636419] Key type .llcrypt unregistered [ 8622.638762] Key type ._llcrypt unregistered [ 8627.519729] Key type ._llcrypt registered [ 8627.522100] Key type .llcrypt registered [ 8627.820505] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8627.828619] alg: No test for adler32 (adler32-zlib) [ 8628.847359] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 8628.975152] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 8630.599828] Key type lgssc registered [ 8631.314433] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8676.015238] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8679.112397] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8692.178917] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8693.486617] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 8694.793158] Lustre: Unmounted lustre-client [ 8721.679882] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 19:15:49 (1788909349) [ 8741.565394] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8745.200573] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8771.594379] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8777.242585] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 8778.546347] Lustre: Unmounted lustre-client [ 8814.919355] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 19:17:22 (1788909442) [ 8828.639471] LNet: 1 local NIs in recovery (showing 1): 192.168.201.21@tcp [ 9416.870837] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9419.614454] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9442.500274] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9500.840950] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 19:28:49 (1788910129) [ 9505.888439] Key type lgssc unregistered [ 9506.019644] LNet: 289243:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9506.025903] LNetError: 289243:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9506.035135] LNet: Removed LNI 192.168.201.21@tcp [ 9506.379427] Key type .llcrypt unregistered [ 9506.380664] Key type ._llcrypt unregistered [ 9566.330326] Key type ._llcrypt registered [ 9566.331708] Key type .llcrypt registered [ 9566.495686] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9566.502385] alg: No test for adler32 (adler32-zlib) [ 9567.392946] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 9567.489147] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 9569.087178] Key type lgssc registered [ 9569.624483] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9608.940687] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9611.282738] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9621.197336] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9641.654479] Key type lgssc unregistered [ 9641.815684] LNet: 292913:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9641.818812] LNetError: 292913:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9641.825505] LNet: Removed LNI 192.168.201.21@tcp [ 9642.124281] Key type .llcrypt unregistered [ 9642.126298] Key type ._llcrypt unregistered [ 9673.197649] Key type ._llcrypt registered [ 9673.198704] Key type .llcrypt registered [ 9673.758892] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 19:31:41 (1788910301) [ 9682.405318] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9682.411902] alg: No test for adler32 (adler32-zlib) [ 9683.279072] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 9683.377349] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 9684.967269] Key type lgssc registered [ 9685.405313] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9705.210896] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 19:32:13 (1788910333) [ 9709.998852] Key type lgssc unregistered [ 9710.123852] LNet: 296455:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9710.127473] LNetError: 296455:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9710.134469] LNet: Removed LNI 192.168.201.21@tcp [ 9710.435861] Key type .llcrypt unregistered [ 9710.437639] Key type ._llcrypt unregistered [ 9714.594944] Key type ._llcrypt registered [ 9714.595924] Key type .llcrypt registered [ 9714.783903] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9714.789988] alg: No test for adler32 (adler32-zlib) [ 9715.678741] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 9715.795630] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 9717.391185] Key type lgssc registered [ 9717.852131] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9741.889951] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9744.395107] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9753.595167] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9850.116118] LNet: Added LNI 192.168.201.21@tcp50 [8/256/0/180] [ 9851.072605] LNet: Removed LNI 192.168.201.21@tcp [ 9852.539866] Lustre: 300812:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff96cfc6a35800: config has no valid NIDs, force dynamic NIDs [ 9853.591353] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 9853.651984] Lustre: Unmounted lustre-client [ 9854.734379] Key type lgssc unregistered [ 9854.884160] LNet: 301069:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9854.886780] LNetError: 301069:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9854.902353] LNet: Removed LNI 192.168.201.21@tcp50 [ 9855.139298] Key type .llcrypt unregistered [ 9855.140933] Key type ._llcrypt unregistered [ 9865.705730] Key type ._llcrypt registered [ 9865.713931] Key type .llcrypt registered [ 9865.833863] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9865.840728] alg: No test for adler32 (adler32-zlib) [ 9866.734088] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 9866.830792] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 9868.431304] Key type lgssc registered [ 9868.903651] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9899.029818] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9901.034590] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9903.567443] LNet: Added LNI 192.168.201.21@tcp50 [8/256/0/180] [ 9903.844550] LNet: Removed LNI 192.168.201.21@tcp [ 9904.943546] Lustre: 303747:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff96cfc6a31000: config has no valid NIDs, force dynamic NIDs [ 9905.998367] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [ 9906.189152] Lustre: Unmounted lustre-client [ 9926.529079] Key type lgssc unregistered [ 9926.703948] LNet: 304616:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9926.707813] LNetError: 304616:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9926.714762] LNet: Removed LNI 192.168.201.21@tcp50 [ 9926.976432] Key type .llcrypt unregistered [ 9926.978694] Key type ._llcrypt unregistered [ 9931.335859] Key type ._llcrypt registered [ 9931.336814] Key type .llcrypt registered [ 9931.914808] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 19:36:00 (1788910560) [ 9938.895184] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9938.908420] alg: No test for adler32 (adler32-zlib) [ 9939.781376] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [ 9939.881415] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [ 9941.480935] Key type lgssc registered [ 9941.984684] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9977.300923] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9979.281992] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9988.831397] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10008.576801] Key type lgssc unregistered [10008.690492] LNet: 309343:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10008.693606] LNetError: 309343:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10008.701465] LNet: Removed LNI 192.168.201.21@tcp [10008.937652] Key type .llcrypt unregistered [10008.939096] Key type ._llcrypt unregistered [10012.551646] Key type ._llcrypt registered [10012.552519] Key type .llcrypt registered [10013.010112] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 19:37:21 (1788910641) [10013.832063] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10013.849525] alg: No test for adler32 (adler32-zlib) [10014.720385] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [10014.818383] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [10016.407168] Key type lgssc registered [10016.808247] Lustre: Echo OBD driver; http://www.lustre.org/ [10034.668745] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10036.436273] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10044.011506] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10055.666885] Lustre: Mounted lustre-client - version 2.17.58_5_gca60a6d [10077.167633] Lustre: Mounted fs15246-client - version 2.17.58_5_gca60a6d [10081.885914] Lustre: Unmounted fs15246-client [10095.748084] Lustre: Unmounted lustre-client [10119.445469] Key type lgssc unregistered [10119.690858] LNet: 314180:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10119.702846] LNetError: 314180:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10119.720955] LNet: Removed LNI 192.168.201.21@tcp [10120.488258] Key type .llcrypt unregistered [10120.492798] Key type ._llcrypt unregistered [10132.059452] Key type ._llcrypt registered [10132.061054] Key type .llcrypt registered [10133.545633] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 19:39:20 (1788910760) [10135.459716] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10135.626320] alg: No test for adler32 (adler32-zlib) [10136.632525] Lustre: Lustre: Build Version: 2.17.58_5_gca60a6d [10136.830509] LNet: Added LNI 192.168.201.21@tcp [8/256/0/180] [10138.503192] Key type lgssc registered [10139.520408] Lustre: Echo OBD driver; http://www.lustre.org/ [10216.070073] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10218.830933] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10230.469803] Lustre: DEBUG MARKER: oleg121-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10291.183622] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 10168 sec ========= 19:41:59 (1788910919) [10292.023646] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 19:42:00 (1788910920) === [10293.670509] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 19:42:01 (1788910921) === [10312.201552] Key type lgssc unregistered [10312.376522] LNet: 323418:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10312.381231] LNetError: 323418:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10312.392485] LNet: Removed LNI 192.168.201.21@tcp [10312.728594] Key type .llcrypt unregistered [10312.730924] Key type ._llcrypt unregistered