[ 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 425488851 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2880952K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001011] APIC: Switch to symmetric I/O mode setup [ 0.003374] x2apic enabled [ 0.005004] Switched APIC routing to physical x2apic. [ 0.006015] kvm-guest: setup PV IPIs [ 0.008980] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.009000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.009019] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.010011] pid_max: default: 32768 minimum: 301 [ 0.011116] LSM: Security Framework initializing [ 0.012046] Yama: becoming mindful. [ 0.013027] SELinux: Initializing. [ 0.014071] *** VALIDATE selinux *** [ 0.023010] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.027859] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.028152] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029114] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030102] *** VALIDATE tmpfs *** [ 0.031461] *** VALIDATE proc *** [ 0.032268] *** VALIDATE cgroup *** [ 0.033008] *** VALIDATE cgroup2 *** [ 0.034267] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.035142] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036009] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.037030] Spectre V2 : User space: Vulnerable [ 0.038010] Speculative Store Bypass: Vulnerable [ 0.041415] debug: unmapping init [mem 0xffffffff97059000-0xffffffff97060fff] [ 0.043186] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.044751] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.045021] ... version: 2 [ 0.046014] ... bit width: 48 [ 0.047013] ... generic registers: 4 [ 0.048014] ... value mask: 0000ffffffffffff [ 0.049015] ... max period: 00007fffffffffff [ 0.050016] ... fixed-purpose events: 3 [ 0.051014] ... event mask: 000000070000000f [ 0.052307] rcu: Hierarchical SRCU implementation. [ 0.054474] smp: Bringing up secondary CPUs ... [ 0.055550] x86: Booting SMP configuration: [ 0.056030] .... node #0, CPUs: #1 #2 #3 [ 0.059643] smp: Brought up 1 node, 4 CPUs [ 0.061014] smpboot: Max logical packages: 1 [ 0.062021] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.142040] node 0 deferred pages initialised in 77ms [ 0.144640] devtmpfs: initialized [ 0.146246] x86/mm: Memory block size: 128MB [ 0.148727] gcov: version magic: 0x41383552 [ 0.151259] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.154073] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.157283] pinctrl core: initialized pinctrl subsystem [ 0.159132] [ 0.159526] ************************************************************* [ 0.161010] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.163013] ** ** [ 0.165012] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.167012] ** ** [ 0.169009] ** This means that this kernel is built to expose internal ** [ 0.170011] ** IOMMU data structures, which may compromise security on ** [ 0.172012] ** your system. ** [ 0.176013] ** ** [ 0.178012] ** If you see this message and you are not debugging the ** [ 0.181012] ** kernel, report this immediately to your vendor! ** [ 0.183012] ** ** [ 0.185009] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.187013] ************************************************************* [ 0.189642] NET: Registered protocol family 16 [ 0.191437] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.194057] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.197046] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.200095] cpuidle: using governor menu [ 0.201689] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.203534] PCI: Using configuration type 1 for base access [ 0.206109] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.215139] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.218035] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.222079] cryptd: max_cpu_qlen set to 1000 [ 0.224253] ACPI: Added _OSI(Module Device) [ 0.227017] ACPI: Added _OSI(Processor Device) [ 0.228014] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.230016] ACPI: Added _OSI(Processor Aggregator Device) [ 0.235000] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.240376] ACPI: Interpreter enabled [ 0.241054] ACPI: PM: (supports S0 S3 S4 S5) [ 0.243013] ACPI: Using IOAPIC for interrupt routing [ 0.244088] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.247292] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.257000] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.259042] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.261018] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.264089] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.269107] acpiphp: Slot [2] registered [ 0.270093] acpiphp: Slot [5] registered [ 0.271120] acpiphp: Slot [6] registered [ 0.273118] acpiphp: Slot [3] registered [ 0.275102] acpiphp: Slot [4] registered [ 0.276126] acpiphp: Slot [7] registered [ 0.278103] acpiphp: Slot [8] registered [ 0.279090] acpiphp: Slot [9] registered [ 0.280109] acpiphp: Slot [10] registered [ 0.282075] acpiphp: Slot [11] registered [ 0.283093] acpiphp: Slot [12] registered [ 0.285120] acpiphp: Slot [13] registered [ 0.287121] acpiphp: Slot [14] registered [ 0.288092] acpiphp: Slot [15] registered [ 0.290108] acpiphp: Slot [16] registered [ 0.291089] acpiphp: Slot [17] registered [ 0.292086] acpiphp: Slot [18] registered [ 0.293088] acpiphp: Slot [19] registered [ 0.294061] acpiphp: Slot [20] registered [ 0.295088] acpiphp: Slot [21] registered [ 0.297107] acpiphp: Slot [22] registered [ 0.299106] acpiphp: Slot [23] registered [ 0.300118] acpiphp: Slot [24] registered [ 0.302095] acpiphp: Slot [25] registered [ 0.303102] acpiphp: Slot [26] registered [ 0.304080] acpiphp: Slot [27] registered [ 0.305090] acpiphp: Slot [28] registered [ 0.307094] acpiphp: Slot [29] registered [ 0.308103] acpiphp: Slot [30] registered [ 0.310075] acpiphp: Slot [31] registered [ 0.311063] PCI host bridge to bus 0000:00 [ 0.312023] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.315031] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.317026] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.321025] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.324020] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.326025] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.329169] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.332090] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.335253] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.341537] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.345849] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.348015] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.350016] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.353017] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.355480] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.358305] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.361043] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.364099] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.368929] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.379012] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.383015] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.387581] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.392825] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.397011] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.408912] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.415675] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.422017] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.427015] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.439014] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.450000] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.452396] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.454430] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.456321] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.458217] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.462189] iommu: Default domain type: Passthrough [ 0.464378] SCSI subsystem initialized [ 0.466118] ACPI: bus type USB registered [ 0.467085] usbcore: registered new interface driver usbfs [ 0.470104] usbcore: registered new interface driver hub [ 0.472079] usbcore: registered new device driver usb [ 0.474155] pps_core: LinuxPPS API ver. 1 registered [ 0.476012] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.479057] PTP clock support registered [ 0.482101] EDAC MC: Ver: 3.0.0 [ 0.484157] PCI: Using ACPI for IRQ routing [ 0.485614] NetLabel: Initializing [ 0.487015] NetLabel: domain hash size = 128 [ 0.488011] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.490094] NetLabel: unlabeled traffic allowed by default [ 0.493128] vgaarb: loaded [ 0.494309] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.496021] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.503389] clocksource: Switched to clocksource kvm-clock [ 0.614206] VFS: Disk quotas dquot_6.6.0 [ 0.615986] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.618315] *** VALIDATE ramfs *** [ 0.619641] *** VALIDATE hugetlbfs *** [ 0.621153] pnp: PnP ACPI init [ 0.623203] pnp: PnP ACPI: found 6 devices [ 0.647663] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.651390] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.653938] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.655798] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.658555] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.661475] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.664767] NET: Registered protocol family 2 [ 0.667730] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.672915] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.676631] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.681822] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.685656] TCP: Hash tables configured (established 65536 bind 65536) [ 0.688774] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.692210] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.695235] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.698353] NET: Registered protocol family 1 [ 0.701395] RPC: Registered named UNIX socket transport module. [ 0.703755] RPC: Registered udp transport module. [ 0.705540] RPC: Registered tcp transport module. [ 0.707283] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.709688] NET: Registered protocol family 44 [ 0.711818] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.714238] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.716366] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.719000] PCI: CLS 0 bytes, default 64 [ 0.720696] Unpacking initramfs... [ 2.117079] debug: unmapping init [mem 0xffff98823cc64000-0xffff98823ffcffff] [ 2.121582] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.124203] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.127518] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.625594] Initialise system trusted keyrings [ 2.627220] Key type blacklist registered [ 2.629574] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.638566] zbud: loaded [ 2.641583] *** VALIDATE nfs *** [ 2.643044] *** VALIDATE nfs4 *** [ 2.645032] pstore: using deflate compression [ 2.656560] Platform Keyring initialized [ 2.754682] NET: Registered protocol family 38 [ 2.756177] Key type asymmetric registered [ 2.757594] Asymmetric key parser 'x509' registered [ 2.759257] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.761660] io scheduler mq-deadline registered [ 2.764399] io scheduler kyber registered [ 2.766945] io scheduler bfq registered [ 2.769414] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.773611] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.777745] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.782100] ACPI: Power Button [PWRF] [ 2.787416] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.792731] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.803753] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.832540] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.861638] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.866180] Non-volatile memory driver v1.3 [ 2.867447] Linux agpgart interface v0.103 [ 2.895181] virtio_blk virtio1: [vda] 146680 512-byte logical blocks (75.1 MB/71.6 MiB) [ 2.897890] vda: detected capacity change from 0 to 75100160 [ 2.910099] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.912814] vdb: detected capacity change from 0 to 1073741824 [ 2.917823] libphy: Fixed MDIO Bus: probed [ 2.922916] usbcore: registered new interface driver usbserial_generic [ 2.925310] usbserial: USB Serial support registered for generic [ 2.927413] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.931234] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.932958] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.935235] mousedev: PS/2 mouse device common for all mice [ 2.937615] rtc_cmos 00:05: RTC can wake from S4 [ 2.940250] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.940623] rtc_cmos 00:05: registered as rtc0 [ 2.946875] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 2.948205] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.953879] intel_pstate: CPU model not supported [ 2.957637] hid: raw HID events driver (C) Jiri Kosina [ 2.959918] usbcore: registered new interface driver usbhid [ 2.961722] usbhid: USB HID core driver [ 2.962908] drop_monitor: Initializing network drop monitor service [ 2.965226] Initializing XFRM netlink socket [ 2.965701] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 2.967401] NET: Registered protocol family 10 [ 2.968488] Segment Routing with IPv6 [ 2.974861] NET: Registered protocol family 17 [ 2.976688] mpls_gso: MPLS GSO support [ 2.981754] RAS: Correctable Errors collector initialized. [ 2.983801] AVX version of gcm_enc/dec engaged. [ 2.985434] AES CTR mode by8 optimization enabled [ 3.059437] sched_clock: Marking stable (3059413424, 0)->(3956256698, -896843274) [ 3.062538] registered taskstats version 1 [ 3.064443] Loading compiled-in X.509 certificates [ 3.067113] zswap: loaded using pool lzo/zbud [ 3.088644] Key type big_key registered [ 3.100490] Key type encrypted registered [ 3.102128] ima: No TPM chip found, activating TPM-bypass! [ 3.104210] ima: Allocated hash algorithm: sha1 [ 3.106034] ima: No architecture policies found [ 3.107799] evm: Initialising EVM extended attributes: [ 3.109575] evm: security.selinux [ 3.110797] evm: security.ima [ 3.111923] evm: security.capability [ 3.113127] evm: HMAC attrs: 0x1 [ 3.115723] rtc_cmos 00:05: setting system clock to 2026-09-08 01:57:25 UTC (1788832645) [ 3.122056] debug: unmapping init [mem 0xffffffff98003000-0xffffffff981fffff] [ 3.125471] debug: unmapping init [mem 0xffffffff96d82000-0xffffffff97058fff] [ 3.134111] Write protecting the kernel read-only data: 28672k [ 3.137612] debug: unmapping init [mem 0xffffffff95403000-0xffffffff955fffff] [ 3.140364] debug: unmapping init [mem 0xffffffff95d14000-0xffffffff95dfffff] [ 3.170698] 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.179641] systemd[1]: Detected virtualization kvm. [ 3.181554] systemd[1]: Detected architecture x86-64. [ 3.183312] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.209181] systemd[1]: No hostname configured. [ 3.210974] systemd[1]: Set hostname to . [ 3.213088] random: systemd: uninitialized urandom read (16 bytes read) [ 3.215722] systemd[1]: Initializing machine ID from random generator. [ 3.336064] random: systemd: uninitialized urandom read (16 bytes read) [ 3.339091] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ 3.345341] random: systemd: uninitialized urandom read (16 bytes read) [ 3.351778] systemd[1]: Started Memstrack Anylazing Service. [ OK ] Started Memstrack Anylazing Service. [ 3.361098] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ OK ] Listening on udev Kernel Socket. Starting Setup Virtual Console... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Timers. [ OK ] Reached target Initrd Root Device. Starting Apply Kernel Variables... [ OK ] Reached target Swap. [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. 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... [ 3.897269] device-mapper: uevent: version 1.0.3 [ 3.899399] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 4.554850] virtio_net virtio0 ens2: renamed from eth0 [ 4.561027] scsi host0: ata_piix [ 4.582142] random: fast init done [ 4.619251] scsi host1: ata_piix [ 4.621224] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.623696] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.729146] dracut-initqueue[586]: RTNETLINK answers: File exists [ 9.623320] random: crng init done [ 9.624927] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 9.790560] 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 target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped Apply Kernel Variables. Stopping udev Kernel Device Manager... [ OK ] Stopped target Swap. [ 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... [ 10.840434] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.093823] SELinux: Disabled at runtime. [ 11.151296] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 11.159151] systemd[1]: Detected virtualization kvm. [ 11.161229] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 11.634756] systemd[1]: initrd-switch-root.service: Succeeded. [ 11.639287] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 11.644300] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 11.648770] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 11.652552] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 11.660084] systemd[1]: Starting Journal Service... Starting Journal Service... [ 11.667143] systemd[1]: Mounting Kernel Debug File System... Mounting Kernel Debug File System... [ OK ] Started Dispatch Password Requests to Console Directory Watch. Starting Remount Root and Kernel File Systems... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Paths. Activating swap /dev/disk/by-label/SWAP... Mounting Huge Pages File System... Mounting POSIX Message Queue File System... Starting Apply Kernel Variables... [ OK ] Reached target rpc_pipefs.target. [ OK ] Listening on RPCbind Server Activation Socket. [FAILED[ 11.734796] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS ] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Listening on Process Core Dump Socket. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Created slice system-getty.slice. [ OK ] Listening on udev Control Socket. [ OK ] Created slice system-sshd\x2dkeygen.slice. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ OK ] Reached target RPC Port Mapper. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [ OK ] Stopped target Initrd File Systems. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Started Journal Service. [ OK ] Mounted Kernel Debug File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ 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. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started 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 ] Mounted /mnt. [ 12.016772] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Coldplug all Devices. [ OK ] Started udev Kernel Device Manager. [ 12.382398] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 12.418904] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 12.491041] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 12.503955] EDAC sbridge: Ver: 1.1.2 [ 13.539174] Key type dns_resolver registered [ 13.846754] NFS: Registering the id_resolver key type [ 13.849270] Key type id_resolver registered [ 13.851294] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... Starting Load/Save Random Seed... Starting Create Volatile Files and Directories... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Restore /run/initramfs on shutdown... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Login Service... [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started irqbalance daemon. [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting Network Manager Wait Online... Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started OpenSSH server daemon. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting System Logging Service... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg350-client login: [ 45.517697] libcfs: loading out-of-tree module taints kernel. [ 45.600230] Key type ._llcrypt registered [ 45.611116] Key type .llcrypt registered [ 45.851592] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 45.858682] alg: No test for adler32 (adler32-zlib) [ 46.900691] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [ 47.253549] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 48.895160] Key type lgssc registered [ 49.552527] Lustre: Echo OBD driver; http://www.lustre.org/ [ 182.734191] hrtimer: interrupt took 2729608 ns [ 216.492352] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 222.090327] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 236.492860] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing check_logdir /tmp/testlogs/ [ 241.044418] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing yml_node [ 242.152412] Lustre: lustre-OST0000-osc-ffff98828bc0a000: disconnect after 23s idle [ 246.035544] Lustre: DEBUG MARKER: Client: 2.17.57.106 [ 249.093556] Lustre: DEBUG MARKER: MDS: 2.17.57.106 [ 252.127207] Lustre: DEBUG MARKER: OSS: 2.17.57.106 [ 254.078140] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Mon Sep 7 22:01:34 EDT 2026 [ 273.823451] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 275.481718] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 278.012893] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 288.373523] Lustre: Unmounted lustre-client [ 401.895768] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 407.927506] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 430.977122] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 477.218926] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 22:05:17 (1788833117) [ 479.941896] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 481.709333] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 22:05:22 (1788833122) [ 518.962613] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 525.889042] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 549.303858] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 550.225285] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 559.563100] Lustre: Unmounted lustre-client [ 587.703492] Key type lgssc unregistered [ 588.072645] LNet: 15043:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 588.081650] LNetError: 15043:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 588.097907] LNet: Removed LNI 192.168.203.50@tcp [ 588.926909] Key type .llcrypt unregistered [ 588.930129] Key type ._llcrypt unregistered [ 605.451444] Key type ._llcrypt registered [ 605.453858] Key type .llcrypt registered [ 606.790813] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 22:07:27 (1788833247) [ 608.010839] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 608.170029] alg: No test for adler32 (adler32-zlib) [ 609.439796] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [ 609.666871] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 611.392095] Key type lgssc registered [ 612.917173] Lustre: Echo OBD driver; http://www.lustre.org/ [ 685.390634] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 772.319987] Lustre: Unmounted lustre-client [ 801.430645] Key type lgssc unregistered [ 801.696803] LNet: 18822:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 801.707540] LNetError: 18822:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 801.734306] LNet: Removed LNI 192.168.203.50@tcp [ 802.489508] Key type .llcrypt unregistered [ 802.491820] Key type ._llcrypt unregistered [ 812.756904] Key type ._llcrypt registered [ 812.762915] Key type .llcrypt registered [ 814.165968] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 22:10:54 (1788833454) [ 816.986432] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 817.046326] alg: No test for adler32 (adler32-zlib) [ 818.225698] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [ 818.511482] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 820.215553] Key type lgssc registered [ 821.892886] Lustre: Echo OBD driver; http://www.lustre.org/ [ 876.865749] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 882.606479] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 908.494323] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 911.311509] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 923.432838] Lustre: Unmounted lustre-client [ 952.805247] Key type lgssc unregistered [ 953.108859] LNet: 22420:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 953.119673] LNetError: 22420:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 953.151904] LNet: Removed LNI 192.168.203.50@tcp [ 953.890932] Key type .llcrypt unregistered [ 953.893865] Key type ._llcrypt unregistered [ 964.784521] Key type ._llcrypt registered [ 964.790481] Key type .llcrypt registered [ 966.286405] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 22:13:27 (1788833607) [ 968.949329] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 969.060321] alg: No test for adler32 (adler32-zlib) [ 970.228987] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [ 970.489173] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 972.218654] Key type lgssc registered [ 973.328490] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1027.615781] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1034.959570] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1059.466228] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1061.203937] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 1068.105379] Lustre: Unmounted lustre-client [ 1097.412540] Key type lgssc unregistered [ 1097.791810] LNet: 25610:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1097.806957] LNetError: 25610:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1097.834493] LNet: Removed LNI 192.168.203.50@tcp [ 1098.809755] Key type .llcrypt unregistered [ 1098.813768] Key type ._llcrypt unregistered [ 1116.526262] Key type ._llcrypt registered [ 1116.528231] Key type .llcrypt registered [ 1118.097205] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 22:15:58 (1788833758) [ 1120.883498] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1120.981688] alg: No test for adler32 (adler32-zlib) [ 1122.130574] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [ 1122.460260] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 1124.247302] Key type lgssc registered [ 1125.849398] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1181.087887] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1187.767389] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1210.639689] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1213.613086] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 1224.171455] Lustre: lustre-MDT0000-mdc-ffff9882829aa000: Connection to lustre-MDT0000 (at 192.168.203.150@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1234.408202] Lustre: lustre-MDT0001-mdc-ffff9882829aa000: Connection to lustre-MDT0001 (at 192.168.203.150@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1241.096165] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1244.639439] Lustre: 26566:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788833871/real 1788833871] req@000000004270d937 x1875728152926336/t0(0) o400->MGC192.168.203.150@tcp@192.168.203.150@tcp:26/25 lens 224/224 e 0 to 1 dl 1788833887 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1244.676996] LustreError: MGC192.168.203.150@tcp: Connection to MGS (at 192.168.203.150@tcp) was lost; in progress operations using this service will fail [ 1251.478165] Lustre: Unmounted lustre-client [ 1266.577733] Key type lgssc unregistered [ 1266.881975] LNet: 29261:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1266.898781] LNetError: 29261:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1266.917466] LNet: Removed LNI 192.168.203.50@tcp [ 1267.854178] Key type .llcrypt unregistered [ 1267.860561] Key type ._llcrypt unregistered [ 1289.751754] Key type ._llcrypt registered [ 1289.753670] Key type .llcrypt registered [ 1291.347121] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 22:18:52 (1788833932) [ 1293.892779] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1293.905429] alg: No test for adler32 (adler32-zlib) [ 1294.961261] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [ 1295.201447] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 1296.954986] Key type lgssc registered [ 1298.413762] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1358.974410] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1365.595580] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1390.896266] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1398.349917] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 1403.372385] Lustre: lustre-OST0000-osc-ffff98829812b800: Connection to lustre-OST0000 (at 192.168.203.150@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1411.251427] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1421.625426] Lustre: Unmounted lustre-client [ 1450.261704] Key type lgssc unregistered [ 1450.573392] LNet: 32879:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1450.581828] LNetError: 32879:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1450.600904] LNet: Removed LNI 192.168.203.50@tcp [ 1451.279703] Key type .llcrypt unregistered [ 1451.299069] Key type ._llcrypt unregistered [ 1469.819527] Key type ._llcrypt registered [ 1469.823548] Key type .llcrypt registered [ 1471.594829] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 22:21:51 (1788834111) [ 1474.382629] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1474.429102] alg: No test for adler32 (adler32-zlib) [ 1475.798849] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [ 1476.307097] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 1478.040202] Key type lgssc registered [ 1480.082564] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1533.493803] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1539.821307] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1564.366569] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1567.370803] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 1571.734977] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1575.597564] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1586.928678] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-09-0722h23m48s [ 1589.594215] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1593.319299] Lustre: lustre-MDT0000-mdc-ffff9882829ac000: Connection to lustre-MDT0000 (at 192.168.203.150@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1600.218517] LustreError: 36666:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff9882829ac000: can't stat MDS #0: rc = -19 [ 1601.906294] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1610.737145] LustreError: MGC192.168.203.150@tcp: Connection to MGS (at 192.168.203.150@tcp) was lost; in progress operations using this service will fail [ 1610.770498] Lustre: Evicted from MGS (at 192.168.203.150@tcp) after server handle changed from 0x598c49d98f8baeea to 0x598c49d98f8bb1ad [ 1610.791331] Lustre: MGC192.168.203.150@tcp: Connection restored to 192.168.203.150@tcp (at 192.168.203.150@tcp) [ 1611.819169] LustreError: lustre-MDT0000-mdc-ffff9882829ac000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1611.845346] Lustre: lustre-MDT0000-mdc-ffff9882829ac000: Connection restored to 192.168.203.150@tcp (at 192.168.203.150@tcp) [ 1617.454598] Lustre: DEBUG MARKER: Wait for df (36666) ... [ 1618.870762] Lustre: DEBUG MARKER: done [ 1621.180072] Lustre: Unmounted lustre-client [ 1648.804140] Key type lgssc unregistered [ 1649.116852] LNet: 37681:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1649.122381] LNetError: 37681:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1649.136708] LNet: Removed LNI 192.168.203.50@tcp [ 1649.785433] Key type .llcrypt unregistered [ 1649.792204] Key type ._llcrypt unregistered [ 1685.868505] Key type ._llcrypt registered [ 1685.871633] Key type .llcrypt registered [ 1687.088363] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 22:25:28 (1788834328) [ 1690.221952] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1690.239307] alg: No test for adler32 (adler32-zlib) [ 1691.469833] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [ 1691.714394] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 1693.411129] Key type lgssc registered [ 1694.671252] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1745.816901] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1752.016556] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1776.294459] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1778.960396] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 1783.707412] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-09-0722h27m04s [ 1785.453845] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1792.497310] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1795.354810] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1816.031151] Lustre: 41827:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788834437/real 1788834437] req@00000000c0dcb216 x1875728750615296/t0(0) o101->lustre-MDT0000-mdc-ffff9882a0f32800@192.168.203.150@tcp:12/10 lens 576/1152 e 0 to 1 dl 1788834457 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1816.071076] Lustre: lustre-MDT0000-mdc-ffff9882a0f32800: Connection to lustre-MDT0000 (at 192.168.203.150@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1816.116863] Lustre: lustre-MDT0000-mdc-ffff9882a0f32800: Connection restored to 192.168.203.150@tcp (at 192.168.203.150@tcp) [ 1838.290795] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1842.355681] Lustre: DEBUG MARKER: done [ 1847.269958] Lustre: Unmounted lustre-client [ 1875.683296] Key type lgssc unregistered [ 1875.936897] LNet: 42704:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1875.944491] LNetError: 42704:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1875.967288] LNet: Removed LNI 192.168.203.50@tcp [ 1876.711659] Key type .llcrypt unregistered [ 1876.714225] Key type ._llcrypt unregistered [ 1912.325098] Key type ._llcrypt registered [ 1912.326831] Key type .llcrypt registered [ 1913.899974] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 22:29:14 (1788834554) [ 1915.449580] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1917.341441] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 22:29:18 (1788834558) [ 1921.481127] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1921.510703] alg: No test for adler32 (adler32-zlib) [ 1922.568959] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [ 1922.833599] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 1924.503465] Key type lgssc registered [ 1925.621353] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1957.143240] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 22:29:58 (1788834598) [ 1998.635727] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2004.376767] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2026.410914] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2027.990123] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 2031.463748] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 2035.245454] Lustre: Unmounted lustre-client [ 2052.479736] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 2104.464695] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2110.145684] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2110.900166] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 2115.014836] Lustre: Unmounted lustre-client [ 2183.600938] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2189.376923] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2189.988338] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 2194.174346] Lustre: Unmounted lustre-client [ 2211.976559] Lustre: DEBUG MARKER: files compared the same [ 2235.317911] Key type lgssc unregistered [ 2235.555417] LNet: 50322:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2235.572281] LNetError: 50322:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2235.587650] LNet: Removed LNI 192.168.203.50@tcp [ 2236.236246] Key type .llcrypt unregistered [ 2236.239197] Key type ._llcrypt unregistered [ 2251.785408] Key type ._llcrypt registered [ 2251.788941] Key type .llcrypt registered [ 2253.212463] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 22:34:54 (1788834894) [ 2256.249848] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2256.265216] alg: No test for adler32 (adler32-zlib) [ 2257.371258] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [ 2257.661890] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 2259.343598] Key type lgssc registered [ 2260.705181] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2314.767771] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2322.635257] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2348.283710] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2360.372299] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 2364.999707] Lustre: Unmounted lustre-client [ 2393.540044] Key type lgssc unregistered [ 2393.729639] LNet: 53905:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2393.736715] LNetError: 53905:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2393.758951] LNet: Removed LNI 192.168.203.50@tcp [ 2394.460311] Key type .llcrypt unregistered [ 2394.462266] Key type ._llcrypt unregistered [ 2409.994319] Key type ._llcrypt registered [ 2409.996229] Key type .llcrypt registered [ 2411.120942] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 22:37:32 (1788835052) [ 2413.824515] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2413.848342] alg: No test for adler32 (adler32-zlib) [ 2415.027139] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [ 2415.307335] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 2416.991186] Key type lgssc registered [ 2418.313817] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2505.540565] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2540.553800] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2546.444210] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2573.929907] Key type lgssc unregistered [ 2574.168509] LNet: 57386:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2574.174587] LNetError: 57386:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2574.193327] LNet: Removed LNI 192.168.203.50@tcp [ 2574.836935] Key type .llcrypt unregistered [ 2574.839100] Key type ._llcrypt unregistered [ 2589.144332] Key type ._llcrypt registered [ 2589.147568] Key type .llcrypt registered [ 2590.403297] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 22:40:31 (1788835231) [ 2593.413632] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2593.485799] alg: No test for adler32 (adler32-zlib) [ 2594.536328] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [ 2594.715985] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 2596.359198] Key type lgssc registered [ 2597.373630] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2679.031167] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 2686.958286] Lustre: Unmounted lustre-client [ 2736.562408] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 22:42:57 (1788835377) [ 2854.326950] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2876.972603] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 2884.430619] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2884.446915] LustreError: 63863:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff98829812b800: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 2884.479182] Lustre: 58365:0:(llite_lib.c:4342:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.203.150@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 2894.802733] Lustre: Unmounted lustre-client [ 2944.722901] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 22:46:25 (1788835585) [ 2956.232630] Key type lgssc unregistered [ 2956.514221] LNet: 65082:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2956.523283] LNetError: 65082:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2956.548515] LNet: Removed LNI 192.168.203.50@tcp [ 2957.222379] Key type .llcrypt unregistered [ 2957.227598] Key type ._llcrypt unregistered [ 2968.867556] Key type ._llcrypt registered [ 2968.872331] Key type .llcrypt registered [ 2969.448476] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2969.469617] alg: No test for adler32 (adler32-zlib) [ 2970.643341] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [ 2970.945526] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 2972.648440] Key type lgssc registered [ 2974.046576] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3023.145931] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3029.347633] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3053.755782] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3055.666299] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 3064.053832] Lustre: Unmounted lustre-client [ 3093.645347] Key type lgssc unregistered [ 3094.051134] LNet: 68099:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3094.065739] LNetError: 68099:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3094.098759] LNet: Removed LNI 192.168.203.50@tcp [ 3094.864591] Key type .llcrypt unregistered [ 3094.867089] Key type ._llcrypt unregistered [ 3107.022402] Key type ._llcrypt registered [ 3107.025683] Key type .llcrypt registered [ 3107.484524] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3107.504675] alg: No test for adler32 (adler32-zlib) [ 3108.647827] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [ 3109.016632] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 3110.672096] Key type lgssc registered [ 3112.005370] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3217.903476] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3223.930597] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3243.679391] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3245.410379] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 3248.008351] Lustre: Unmounted lustre-client [ 3269.777650] Key type lgssc unregistered [ 3270.151975] LNet: 71526:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3270.157933] LNetError: 71526:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3270.179553] LNet: Removed LNI 192.168.203.50@tcp [ 3270.792936] Key type .llcrypt unregistered [ 3270.798119] Key type ._llcrypt unregistered [ 3285.990403] Key type ._llcrypt registered [ 3285.993156] Key type .llcrypt registered [ 3287.062444] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 22:52:08 (1788835928) [ 3288.983354] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3289.012697] alg: No test for adler32 (adler32-zlib) [ 3290.013559] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [ 3290.229519] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 3291.895186] Key type lgssc registered [ 3292.998351] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3339.943492] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3345.638271] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3365.818702] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3368.541596] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 3373.219241] Lustre: Unmounted lustre-client [ 3373.623748] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 3376.437278] Lustre: Unmounted lustre-client [ 3402.073833] Key type lgssc unregistered [ 3402.374326] LNet: 75209:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3402.385163] LNetError: 75209:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3402.408070] LNet: Removed LNI 192.168.203.50@tcp [ 3403.160609] Key type .llcrypt unregistered [ 3403.167715] Key type ._llcrypt unregistered [ 3413.517319] Key type ._llcrypt registered [ 3413.525439] Key type .llcrypt registered [ 3413.925314] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3413.949802] alg: No test for adler32 (adler32-zlib) [ 3415.003196] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [ 3415.300527] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 3417.047265] Key type lgssc registered [ 3418.478428] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3463.690488] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3468.681678] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3486.539707] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3488.442218] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 3492.779538] Lustre: Unmounted lustre-client [ 3520.654449] Key type lgssc unregistered [ 3520.899395] LNet: 78238:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3520.909828] LNetError: 78238:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3520.928561] LNet: Removed LNI 192.168.203.50@tcp [ 3521.578804] Key type .llcrypt unregistered [ 3521.583815] Key type ._llcrypt unregistered [ 3537.718772] Key type ._llcrypt registered [ 3537.725244] Key type .llcrypt registered [ 3539.242077] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 22:56:19 (1788836179) [ 3541.390723] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3541.425256] alg: No test for adler32 (adler32-zlib) [ 3542.649809] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [ 3543.023651] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 3544.729829] Key type lgssc registered [ 3546.459514] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3598.375369] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3604.909914] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3626.586949] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3628.696195] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 3634.344373] Lustre: lustre: root_squash is set to 0:0 [ 3642.303700] Lustre: lustre: nosquash_nids is cleared [ 3650.615343] Lustre: lustre: root_squash is set to 500:500 [ 3659.818722] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.203.50@tcp 192.168.0.[2,10]@tcp [ 3667.986143] Lustre: lustre: nosquash_nids set to 192.168.203.50/24@tcp [ 3671.013908] Lustre: Unmounted lustre-client [ 3706.880925] Key type lgssc unregistered [ 3707.174214] LNet: 82184:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3707.191062] LNetError: 82184:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3707.211543] LNet: Removed LNI 192.168.203.50@tcp [ 3707.968670] Key type .llcrypt unregistered [ 3707.972383] Key type ._llcrypt unregistered [ 3726.006256] Key type ._llcrypt registered [ 3726.009971] Key type .llcrypt registered [ 3728.260578] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 22:59:28 (1788836368) [ 3731.321547] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3733.255685] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 22:59:34 (1788836374) [ 3736.113664] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3736.262063] alg: No test for adler32 (adler32-zlib) [ 3737.288317] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [ 3737.540546] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 3739.268926] Key type lgssc registered [ 3740.672903] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3790.035986] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3796.634687] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3817.087841] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3817.719654] Lustre: lustre: root_squash is set to 500:500 [ 3817.756711] Lustre: lustre: nosquash_nids set to 192.168.203.50/24@tcp [ 3818.851619] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 3828.019223] Lustre: Unmounted lustre-client [ 3856.712324] Key type lgssc unregistered [ 3857.011705] LNet: 86151:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3857.029952] LNetError: 86151:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3857.062564] LNet: Removed LNI 192.168.203.50@tcp [ 3857.935160] Key type .llcrypt unregistered [ 3857.946093] Key type ._llcrypt unregistered [ 3872.718166] Key type ._llcrypt registered [ 3872.722510] Key type .llcrypt registered [ 3873.957585] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 3875.554686] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 23:01:56 (1788836516) [ 3893.954175] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3893.964772] alg: No test for adler32 (adler32-zlib) [ 3895.088036] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [ 3895.356688] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 3897.063188] Key type lgssc registered [ 3898.439065] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3973.297146] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3978.657743] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4000.196844] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4078.334325] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4083.635405] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4100.719794] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4111.316278] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 4138.726226] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff98829007d000.ost_server_uuid 50 [ 4140.135696] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff98829007d000.ost_server_uuid in IDLE state after 0 sec [ 4140.747220] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 4144.052050] Lustre: Unmounted lustre-client [ 4145.730289] Lustre: Unmounted lustre-client [ 4186.865773] Key type lgssc unregistered [ 4187.024303] LNet: 93806:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4187.032496] LNetError: 93806:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4187.049754] LNet: Removed LNI 192.168.203.50@tcp [ 4187.561714] Key type .llcrypt unregistered [ 4187.564268] Key type ._llcrypt unregistered [ 4214.806566] Key type ._llcrypt registered [ 4214.808619] Key type .llcrypt registered [ 4215.726218] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 23:07:37 (1788836857) [ 4231.386109] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4231.404502] alg: No test for adler32 (adler32-zlib) [ 4232.453635] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [ 4232.653084] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 4234.336198] Key type lgssc registered [ 4235.515382] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4303.513496] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4308.216434] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4326.398761] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4329.037530] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 4350.431357] Lustre: 95163:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788836976/real 1788836976] req@0000000070c78d94 x1875731415045376/t0(0) o400->lustre-OST0000-osc-ffff988290aae800@192.168.203.150@tcp:28/4 lens 224/224 e 0 to 1 dl 1788836992 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4350.474956] Lustre: lustre-OST0000-osc-ffff988290aae800: Connection to lustre-OST0000 (at 192.168.203.150@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4355.551167] Lustre: 95164:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788836981/real 1788836981] req@00000000bb2b5690 x1875731415045888/t0(0) o400->lustre-OST0000-osc-ffff988290aae800@192.168.203.150@tcp:28/4 lens 224/224 e 0 to 1 dl 1788836997 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4355.642718] Lustre: lustre-OST0000-osc-ffff988290aae800: Connection restored to 192.168.203.150@tcp (at 192.168.203.150@tcp) [ 4359.775776] Lustre: 95164:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788836986/real 1788836986] req@00000000f7438161 x1875731415046400/t0(0) o400->lustre-OST0000-osc-ffff988290aae800@192.168.203.150@tcp:28/4 lens 224/224 e 0 to 1 dl 1788837002 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4360.684293] Lustre: lustre-MDT0000-mdc-ffff988290aae800: Connection to lustre-MDT0000 (at 192.168.203.150@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4364.767324] Lustre: 95164:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788836991/real 1788836991] req@00000000a216cff6 x1875731415046912/t0(0) o400->lustre-OST0000-osc-ffff988290aae800@192.168.203.150@tcp:28/4 lens 224/224 e 0 to 1 dl 1788837007 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4377.068133] Lustre: 95164:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788837003/real 1788837003] req@00000000bf2f6ba0 x1875731415048576/t0(0) o400->MGC192.168.203.150@tcp@192.168.203.150@tcp:26/25 lens 224/224 e 0 to 1 dl 1788837019 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4377.104525] LustreError: MGC192.168.203.150@tcp: Connection to MGS (at 192.168.203.150@tcp) was lost; in progress operations using this service will fail [ 4387.305170] Lustre: Evicted from MGS (at 192.168.203.150@tcp) after server handle changed from 0x96bc74a83d51129e to 0x96bc74a83d511530 [ 4387.329118] Lustre: MGC192.168.203.150@tcp: Connection restored to 192.168.203.150@tcp (at 192.168.203.150@tcp) [ 4392.036043] Lustre: lustre-MDT0000-mdc-ffff988290aae800: Connection restored to 192.168.203.150@tcp (at 192.168.203.150@tcp) [ 4397.019831] Lustre: Unmounted lustre-client [ 4425.034257] Key type lgssc unregistered [ 4425.403793] LNet: 99460:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4425.410653] LNetError: 99460:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4425.424970] LNet: Removed LNI 192.168.203.50@tcp [ 4426.372739] Key type .llcrypt unregistered [ 4426.375194] Key type ._llcrypt unregistered [ 4442.892533] Key type ._llcrypt registered [ 4442.894772] Key type .llcrypt registered [ 4444.545410] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 23:11:25 (1788837085) [ 4459.935504] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4459.963072] alg: No test for adler32 (adler32-zlib) [ 4461.120688] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [ 4461.396294] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 4463.112599] Key type lgssc registered [ 4464.349811] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4498.106815] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4605.045336] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4610.583480] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4633.131377] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4634.878248] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 4636.912940] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 4638.684185] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 23:14:39 (1788837279) [ 4894.426911] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4904.727271] Lustre: Unmounted lustre-client [ 5043.499968] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5050.943797] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5078.564435] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5124.670867] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 23:22:45 (1788837765) [ 5228.007924] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5233.868222] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5257.888797] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5258.521986] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 5263.854920] Lustre: Unmounted lustre-client [ 5304.843403] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 23:25:45 (1788837945) [ 5416.481211] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5422.712641] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5447.784024] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5448.523253] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 5453.252665] Lustre: Unmounted lustre-client [ 5492.393397] Key type lgssc unregistered [ 5492.625153] LNet: 122496:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5492.636564] LNetError: 122496:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5492.666599] LNet: Removed LNI 192.168.203.50@tcp [ 5493.487161] Key type .llcrypt unregistered [ 5493.494691] Key type ._llcrypt unregistered [ 5508.933408] Key type ._llcrypt registered [ 5508.935325] Key type .llcrypt registered [ 5510.242182] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 23:29:11 (1788838151) [ 5512.464657] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5512.597420] alg: No test for adler32 (adler32-zlib) [ 5513.721523] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [ 5514.015898] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 5515.735192] Key type lgssc registered [ 5516.854959] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5562.883985] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5568.363214] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5588.873114] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5591.671903] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 5605.201055] Lustre: Unmounted lustre-client [ 5634.405888] Key type lgssc unregistered [ 5634.760624] LNet: 126086:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5634.769264] LNetError: 126086:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5634.798461] LNet: Removed LNI 192.168.203.50@tcp [ 5635.680385] Key type .llcrypt unregistered [ 5635.682397] Key type ._llcrypt unregistered [ 5651.839134] Key type ._llcrypt registered [ 5651.841502] Key type .llcrypt registered [ 5652.986891] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 23:31:34 (1788838294) [ 5655.726413] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5655.889496] alg: No test for adler32 (adler32-zlib) [ 5657.161850] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [ 5657.552556] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 5659.463629] Key type lgssc registered [ 5661.198585] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5716.011888] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5721.713389] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5744.415309] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5747.378997] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 5757.427236] Lustre: lustre-OST0000-osc-ffff988292632800: Connection to lustre-OST0000 (at 192.168.203.150@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5778.142254] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff988292632800.ost_server_uuid 50 [ 5780.131696] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 5791.461120] Lustre: Unmounted lustre-client [ 5813.235280] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 23:34:14 (1788838454) [ 5847.302190] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5853.557808] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5879.420362] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5906.168570] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5906.935576] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 5912.055543] Lustre: lustre-OST0000-osc-ffff98828445c000: Connection to lustre-OST0000 (at 192.168.203.150@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5938.323830] Lustre: Unmounted lustre-client [ 5989.912968] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 23:37:10 (1788838630) [ 6022.651752] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6029.089702] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6050.382499] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6072.094857] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6073.044609] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 6078.454153] Lustre: lustre-OST0000-osc-ffff9882929f9000: Connection to lustre-OST0000 (at 192.168.203.150@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6096.368396] Lustre: Unmounted lustre-client [ 6141.282596] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 23:39:42 (1788838782) [ 6229.214270] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6235.226425] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6258.494965] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6334.591306] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6340.637995] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6360.923851] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6396.575057] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 6444.414942] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6446.463887] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6449.676144] Lustre: Unmounted lustre-client [ 6481.171964] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 23:45:21 (1788839121) [ 6513.966591] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6520.983889] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6542.632593] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6577.440030] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6608.740722] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 6651.556851] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6653.459939] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6666.517960] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6676.899422] Lustre: Unmounted lustre-client [ 6743.453883] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 23:49:43 (1788839383) [ 6776.120993] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6782.665130] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6809.078561] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6809.723075] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 6831.251696] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6832.712405] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6851.154551] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9882829a9000.ost_server_uuid 50 [ 6853.462190] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9882829a9000.ost_server_uuid in IDLE state after 0 sec [ 6858.989259] Lustre: Unmounted lustre-client [ 6859.542985] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 6859.558740] Lustre: Skipped 3 previous similar messages [ 6859.670462] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 6861.384759] LustreError: 149516:0:(sec.c:403:import_sec_validate_get()) import 00000000165e127c (NEW) with no sec [ 6931.584438] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 23:52:52 (1788839572) [ 6964.428435] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6971.124786] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6997.264852] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7019.749930] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7020.182995] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 7020.322248] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 7033.569745] Lustre: Unmounted lustre-client [ 7033.578305] Lustre: Skipped 1 previous similar message [ 7073.399505] Key type lgssc unregistered [ 7073.774984] LNet: 152937:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7073.794302] LNetError: 152937:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7073.816473] LNet: Removed LNI 192.168.203.50@tcp [ 7074.689398] Key type .llcrypt unregistered [ 7074.693132] Key type ._llcrypt unregistered [ 7091.828476] Key type ._llcrypt registered [ 7091.831380] Key type .llcrypt registered [ 7093.547389] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 23:55:33 (1788839733) [ 7094.551351] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7094.776783] alg: No test for adler32 (adler32-zlib) [ 7095.880944] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [ 7096.110758] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 7097.767352] Key type lgssc registered [ 7099.089923] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7154.747755] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7161.944418] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7185.438885] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7207.729910] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7209.464040] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7210.741860] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 7215.522728] LustreError: lustre-MDT0001-mdc-ffff9882929f8000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 7215.546544] Lustre: lustre-MDT0001-mdc-ffff9882929f8000: Connection restored to 192.168.203.150@tcp (at 192.168.203.150@tcp) [ 7223.263334] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7225.708685] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7249.947472] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7254.827314] Lustre: Unmounted lustre-client [ 7281.563425] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 23:58:42 (1788839922) [ 7382.631378] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7388.526748] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7413.761381] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7414.673973] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 7442.336299] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7444.063430] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7446.517867] Lustre: lustre-OST0001-osc-ffff9882a0cf2800: Connection to lustre-OST0001 (at 192.168.203.150@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7456.412732] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7466.748623] Lustre: Unmounted lustre-client [ 7501.884931] Key type lgssc unregistered [ 7502.244333] LNet: 162565:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7502.253861] LNetError: 162565:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7502.271471] LNet: Removed LNI 192.168.203.50@tcp [ 7502.973426] Key type .llcrypt unregistered [ 7502.979159] Key type ._llcrypt unregistered [ 7535.820676] Key type ._llcrypt registered [ 7535.823304] Key type .llcrypt registered [ 7537.367649] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 00:02:57 (1788840177) [ 7540.247355] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7540.291489] alg: No test for adler32 (adler32-zlib) [ 7541.552152] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [ 7541.937157] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 7543.639176] Key type lgssc registered [ 7544.920758] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7603.015941] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7609.540887] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7632.941340] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7635.619579] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 7685.593741] Lustre: Unmounted lustre-client [ 7724.209070] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7724.854195] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 7760.054459] Lustre: Unmounted lustre-client [ 7795.902567] Key type lgssc unregistered [ 7796.234877] LNet: 167013:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7796.238403] LNetError: 167013:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7796.257665] LNet: Removed LNI 192.168.203.50@tcp [ 7797.077989] Key type .llcrypt unregistered [ 7797.081614] Key type ._llcrypt unregistered [ 7812.204747] Key type ._llcrypt registered [ 7812.209044] Key type .llcrypt registered [ 7813.683239] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 00:07:34 (1788840454) [ 7816.020057] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7816.046826] alg: No test for adler32 (adler32-zlib) [ 7817.186514] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [ 7817.452254] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 7819.191737] Key type lgssc registered [ 7820.577237] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7870.628220] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7876.087431] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7895.202782] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7897.856987] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 7915.207653] Lustre: Unmounted lustre-client [ 7942.805496] Key type lgssc unregistered [ 7943.017248] LNet: 170765:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7943.024239] LNetError: 170765:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7943.062285] LNet: Removed LNI 192.168.203.50@tcp [ 7943.853943] Key type .llcrypt unregistered [ 7943.861711] Key type ._llcrypt unregistered [ 7956.701777] Key type ._llcrypt registered [ 7956.703593] Key type .llcrypt registered [ 7956.969756] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7957.009927] alg: No test for adler32 (adler32-zlib) [ 7958.121223] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [ 7958.357196] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 7960.079276] Key type lgssc registered [ 7962.122734] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8011.522484] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8016.518398] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8035.495148] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8038.206973] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 8045.842893] Lustre: Unmounted lustre-client [ 8072.037343] Key type lgssc unregistered [ 8072.302894] LNet: 173828:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8072.317400] LNetError: 173828:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8072.337558] LNet: Removed LNI 192.168.203.50@tcp [ 8073.121770] Key type .llcrypt unregistered [ 8073.125356] Key type ._llcrypt unregistered [ 8089.507596] Key type ._llcrypt registered [ 8089.510168] Key type .llcrypt registered [ 8090.966439] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 00:12:11 (1788840731) [ 8093.288253] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8093.304300] alg: No test for adler32 (adler32-zlib) [ 8094.321649] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [ 8094.629979] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 8096.320739] Key type lgssc registered [ 8097.623571] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8146.476984] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8152.024599] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8174.066918] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8176.805229] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 8196.628471] Lustre: Unmounted lustre-client [ 8224.184275] Key type lgssc unregistered [ 8224.413528] LNet: 177578:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8224.423259] LNetError: 177578:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8224.439769] LNet: Removed LNI 192.168.203.50@tcp [ 8225.063966] Key type .llcrypt unregistered [ 8225.065487] Key type ._llcrypt unregistered [ 8237.561819] Key type ._llcrypt registered [ 8237.564291] Key type .llcrypt registered [ 8238.190505] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8238.213796] alg: No test for adler32 (adler32-zlib) [ 8239.451274] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [ 8239.753534] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 8241.567326] Key type lgssc registered [ 8243.401726] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8298.651854] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8304.875514] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8326.293789] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8328.128617] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 8339.703915] Lustre: Unmounted lustre-client [ 8370.015459] Key type lgssc unregistered [ 8370.415039] LNet: 180641:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8370.428313] LNetError: 180641:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8370.447891] LNet: Removed LNI 192.168.203.50@tcp [ 8371.347525] Key type .llcrypt unregistered [ 8371.354427] Key type ._llcrypt unregistered [ 8389.333381] Key type ._llcrypt registered [ 8389.336814] Key type .llcrypt registered [ 8390.776286] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 00:17:11 (1788841031) [ 8415.721774] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8415.741432] alg: No test for adler32 (adler32-zlib) [ 8416.949236] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [ 8417.253290] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 8418.919751] Key type lgssc registered [ 8420.532104] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8507.580382] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8513.963450] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8537.100654] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8581.695607] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 00:20:22 (1788841222) [ 8618.028406] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8624.737187] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8646.335862] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8649.167565] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 8658.451140] Lustre: Unmounted lustre-client [ 8689.534219] Key type lgssc unregistered [ 8689.763914] LNet: 187742:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8689.779229] LNetError: 187742:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8689.800082] LNet: Removed LNI 192.168.203.50@tcp [ 8690.712887] Key type .llcrypt unregistered [ 8690.716906] Key type ._llcrypt unregistered [ 8706.551819] Key type ._llcrypt registered [ 8706.554509] Key type .llcrypt registered [ 8708.337848] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 8710.214981] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 00:22:31 (1788841351) [ 8731.547243] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8731.589733] alg: No test for adler32 (adler32-zlib) [ 8732.549175] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [ 8732.881927] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 8734.671205] Key type lgssc registered [ 8736.067270] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8840.176811] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8847.383433] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8872.468296] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8895.988529] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8898.668290] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 8922.803188] Lustre: Unmounted lustre-client [ 9068.349786] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9073.575874] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9098.600680] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9150.055059] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 00:29:50 (1788841790) [ 9151.830958] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 9153.824983] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 00:29:54 (1788841794) [ 9261.272644] Key type lgssc unregistered [ 9261.597987] LNet: 199190:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9261.609722] LNetError: 199190:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9261.627968] LNet: Removed LNI 192.168.203.50@tcp [ 9262.459934] Key type .llcrypt unregistered [ 9262.464652] Key type ._llcrypt unregistered [ 9279.311181] Key type ._llcrypt registered [ 9279.312977] Key type .llcrypt registered [ 9280.654214] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 00:32:01 (1788841921) [ 9323.061926] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9323.121125] alg: No test for adler32 (adler32-zlib) [ 9324.256279] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [ 9324.625182] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 9326.329607] Key type lgssc registered [ 9327.586183] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9374.354697] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9381.727722] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9420.101979] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9448.776372] Key type lgssc unregistered [ 9449.072632] LNet: 202920:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9449.083464] LNetError: 202920:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9449.100199] LNet: Removed LNI 192.168.203.50@tcp [ 9449.881916] Key type .llcrypt unregistered [ 9449.884900] Key type ._llcrypt unregistered [ 9464.020715] Key type ._llcrypt registered [ 9464.022940] Key type .llcrypt registered [ 9465.436550] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 00:35:06 (1788842106) [ 9468.043791] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9468.212210] alg: No test for adler32 (adler32-zlib) [ 9469.324853] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [ 9469.561967] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 9471.247461] Key type lgssc registered [ 9472.373285] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9522.294652] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9528.377924] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9553.010142] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9555.751709] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [ 9566.197306] Lustre: lustre-MDT0000-mdc-ffff988286df6800: Connection to lustre-MDT0000 (at 192.168.203.150@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9576.426687] Lustre: lustre-MDT0001-mdc-ffff988286df6800: Connection to lustre-MDT0001 (at 192.168.203.150@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9586.655611] Lustre: 203905:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788842213/real 1788842213] req@00000000ac9b9df3 x1875736905465984/t0(0) o400->MGC192.168.203.150@tcp@192.168.203.150@tcp:26/25 lens 224/224 e 0 to 1 dl 1788842229 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9586.686339] LustreError: MGC192.168.203.150@tcp: Connection to MGS (at 192.168.203.150@tcp) was lost; in progress operations using this service will fail [ 9612.289464] Lustre: Evicted from MGS (at 192.168.203.150@tcp) after server handle changed from 0xffca36dd95b985f to 0xffca36dd95bb3b0 [ 9612.320534] Lustre: MGC192.168.203.150@tcp: Connection restored to 192.168.203.150@tcp (at 192.168.203.150@tcp) [ 9621.491148] LustreError: lustre-MDT0000-mdc-ffff988286df6800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 9621.508674] Lustre: lustre-MDT0000-mdc-ffff988286df6800: Connection restored to 192.168.203.150@tcp (at 192.168.203.150@tcp) [ 9632.996849] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9634.699625] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9642.697855] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9644.945675] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9648.477836] Lustre: Unmounted lustre-client [ 9678.335039] Key type lgssc unregistered [ 9678.649994] LNet: 207865:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9678.659414] LNetError: 207865:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9678.687173] LNet: Removed LNI 192.168.203.50@tcp [ 9679.348615] Key type .llcrypt unregistered [ 9679.352711] Key type ._llcrypt unregistered [ 9697.175979] Key type ._llcrypt registered [ 9697.178709] Key type .llcrypt registered [ 9698.878675] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 00:38:59 (1788842339) [ 9702.387258] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9702.415608] alg: No test for adler32 (adler32-zlib) [ 9703.686540] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [ 9704.148327] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [ 9705.839190] Key type lgssc registered [ 9707.074846] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9762.199673] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9769.510421] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9827.619704] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9832.833878] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9867.866959] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9890.052732] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9928.355522] Key type lgssc unregistered [ 9928.607642] LNet: 213159:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9928.631934] LNetError: 213159:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9928.658044] LNet: Removed LNI 192.168.203.50@tcp [ 9929.430818] Key type .llcrypt unregistered [ 9929.433559] Key type ._llcrypt unregistered [ 9972.294171] Key type ._llcrypt registered [ 9972.296630] Key type .llcrypt registered [ 9973.850967] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 00:43:34 (1788842614) [10017.201060] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10017.266945] alg: No test for adler32 (adler32-zlib) [10018.378939] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [10018.682940] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [10020.447401] Key type lgssc registered [10022.054459] Lustre: Echo OBD driver; http://www.lustre.org/ [10107.224095] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10113.611351] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10139.097197] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10188.791808] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 00:47:10 (1788842830) [10197.485513] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 00:47:18 (1788842838) [10231.417562] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10239.333610] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10263.790707] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10265.767397] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [10271.611786] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [10273.686609] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [10275.281929] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [10277.858157] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [10281.460442] Lustre: lustre-MDT0000-mdc-ffff988286816000: Connection to lustre-MDT0000 (at 192.168.203.150@tcp) was lost; in progress operations using this service will wait for recovery to complete [10297.823759] Lustre: 215053:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788842923/real 1788842923] req@000000008d3cc014 x1875737482110720/t0(0) o400->MGC192.168.203.150@tcp@192.168.203.150@tcp:26/25 lens 224/224 e 0 to 1 dl 1788842939 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10297.849832] LustreError: MGC192.168.203.150@tcp: Connection to MGS (at 192.168.203.150@tcp) was lost; in progress operations using this service will fail [10308.077825] Lustre: Evicted from MGS (at 192.168.203.150@tcp) after server handle changed from 0xa9ec9f4b73c7e44e to 0xa9ec9f4b73c7e6c4 [10308.098351] Lustre: MGC192.168.203.150@tcp: Connection restored to 192.168.203.150@tcp (at 192.168.203.150@tcp) [10312.791394] Lustre: lustre-MDT0000-mdc-ffff988286816000: Connection restored to 192.168.203.150@tcp (at 192.168.203.150@tcp) [10322.552392] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10324.449191] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10328.149098] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [10333.677216] Lustre: lustre-MDT0000-mdc-ffff988286816000: Connection to lustre-MDT0000 (at 192.168.203.150@tcp) was lost; in progress operations using this service will wait for recovery to complete [10350.047410] Lustre: 215054:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1788842976/real 1788842976] req@000000004b73bddb x1875737482119936/t0(0) o400->MGC192.168.203.150@tcp@192.168.203.150@tcp:26/25 lens 224/224 e 0 to 1 dl 1788842992 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10350.095276] LustreError: MGC192.168.203.150@tcp: Connection to MGS (at 192.168.203.150@tcp) was lost; in progress operations using this service will fail [10350.132820] Lustre: Evicted from MGS (at 192.168.203.150@tcp) after server handle changed from 0xa9ec9f4b73c7e6c4 to 0xa9ec9f4b73c7e8d8 [10350.140596] Lustre: MGC192.168.203.150@tcp: Connection restored to 192.168.203.150@tcp (at 192.168.203.150@tcp) [10357.743493] Lustre: 221049:0:(mgc_request.c:1900:mgc_process_log()) MGC192.168.203.150@tcp: IR log lustre-cliir failed, not fatal: rc = -2 [10365.632377] Lustre: lustre-MDT0000-mdc-ffff988286816000: Connection restored to 192.168.203.150@tcp (at 192.168.203.150@tcp) [10377.236583] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10379.498065] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10384.069459] Lustre: Unmounted lustre-client [10454.185705] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10460.380873] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10480.782067] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10481.538966] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [10484.284083] Lustre: Unmounted lustre-client [10514.580172] Key type lgssc unregistered [10514.848365] LNet: 225646:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10514.854294] LNetError: 225646:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10515.878800] LNet: Removed LNI 192.168.203.50@tcp [10516.723478] Key type .llcrypt unregistered [10516.730130] Key type ._llcrypt unregistered [10533.143580] Key type ._llcrypt registered [10533.148490] Key type .llcrypt registered [10534.623511] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 00:52:55 (1788843175) [10539.799188] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10539.827443] alg: No test for adler32 (adler32-zlib) [10540.980849] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [10541.326269] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [10543.119197] Key type lgssc registered [10544.706607] Lustre: Echo OBD driver; http://www.lustre.org/ [10596.160098] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10603.253914] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10626.158094] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10627.899094] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [10637.962341] Lustre: Unmounted lustre-client [10705.229806] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10711.515357] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10734.011954] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10734.772490] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [10744.380279] Lustre: Unmounted lustre-client [10818.515598] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10824.568381] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10847.903701] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10848.618277] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [10851.081858] Lustre: Unmounted lustre-client [10879.680857] Key type lgssc unregistered [10879.938153] LNet: 232694:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10879.947370] LNetError: 232694:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10879.971775] LNet: Removed LNI 192.168.203.50@tcp [10880.562738] Key type .llcrypt unregistered [10880.565308] Key type ._llcrypt unregistered [10897.077209] Key type ._llcrypt registered [10897.081475] Key type .llcrypt registered [10898.391635] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 00:58:59 (1788843539) [10901.934543] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10901.955562] alg: No test for adler32 (adler32-zlib) [10903.134629] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [10903.441329] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [10905.169046] Key type lgssc registered [10906.618674] Lustre: Echo OBD driver; http://www.lustre.org/ [10953.762211] Key type lgssc unregistered [10954.266204] LNet: 235014:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10954.290253] LNetError: 235014:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10954.316474] LNet: Removed LNI 192.168.203.50@tcp [10955.204970] Key type .llcrypt unregistered [10955.211173] Key type ._llcrypt unregistered [10987.481466] Key type ._llcrypt registered [10987.486758] Key type .llcrypt registered [10988.190930] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10988.205276] alg: No test for adler32 (adler32-zlib) [10989.540168] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [10989.852527] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [10991.639790] Key type lgssc registered [10993.311951] Lustre: Echo OBD driver; http://www.lustre.org/ [11078.558555] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11084.515958] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11106.429994] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11148.745886] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [11150.040745] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 01:03:11 (1788843791) [11191.532515] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11198.173600] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11220.429563] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11244.302318] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11246.202210] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [11251.178345] Lustre: lustre-OST0001-osc-ffff98828d2fa000: Connection to lustre-OST0001 (at 192.168.203.150@tcp) was lost; in progress operations using this service will wait for recovery to complete [11259.081737] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11269.438196] Lustre: Unmounted lustre-client [11307.725312] Key type lgssc unregistered [11308.043337] LNet: 242141:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11308.059405] LNetError: 242141:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11308.085400] LNet: Removed LNI 192.168.203.50@tcp [11308.943648] Key type .llcrypt unregistered [11308.946499] Key type ._llcrypt unregistered [11341.418789] Key type ._llcrypt registered [11341.421628] Key type .llcrypt registered [11343.247337] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 01:06:23 (1788843983) [11357.585271] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11357.722891] alg: No test for adler32 (adler32-zlib) [11358.873252] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [11359.246902] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [11360.959235] Key type lgssc registered [11362.316722] Lustre: Echo OBD driver; http://www.lustre.org/ [11419.377245] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11425.568852] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11448.644826] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 01:08:09 (1788844089) [11480.945822] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11486.359977] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11511.386028] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11514.266024] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [11525.136463] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [11534.103701] Lustre: Unmounted lustre-client [11635.055961] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11641.083438] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11697.141426] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11697.851919] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [11700.929437] Lustre: Unmounted lustre-client [11728.643967] Key type lgssc unregistered [11728.933256] LNet: 249834:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11728.948397] LNetError: 249834:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11728.978246] LNet: Removed LNI 192.168.203.50@tcp [11729.787822] Key type .llcrypt unregistered [11729.792940] Key type ._llcrypt unregistered [11758.146366] Key type ._llcrypt registered [11758.154045] Key type .llcrypt registered [11758.691803] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11758.711829] alg: No test for adler32 (adler32-zlib) [11759.768229] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [11760.172626] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [11761.943600] Key type lgssc registered [11763.223295] Lustre: Echo OBD driver; http://www.lustre.org/ [11847.934695] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11853.347124] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11874.485480] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11921.878479] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [11924.338964] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 01:16:04 (1788844564) [11960.231849] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11966.635476] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11987.321496] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11991.504474] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [11999.733363] Lustre: Unmounted lustre-client [12028.293297] Key type lgssc unregistered [12028.662600] LNet: 256469:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12028.673992] LNetError: 256469:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12028.716994] LNet: Removed LNI 192.168.203.50@tcp [12029.629765] Key type .llcrypt unregistered [12029.644462] Key type ._llcrypt unregistered [12047.448254] Key type ._llcrypt registered [12047.450889] Key type .llcrypt registered [12049.000863] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [12050.821777] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 01:18:11 (1788844691) [12059.993800] Key type .llcrypt unregistered [12060.006189] Key type ._llcrypt unregistered [12069.445624] Key type ._llcrypt registered [12069.447296] Key type .llcrypt registered [12069.985531] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12070.014904] alg: No test for adler32 (adler32-zlib) [12071.195246] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [12071.589470] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [12073.359205] Key type lgssc registered [12074.737839] Lustre: Echo OBD driver; http://www.lustre.org/ [12121.856407] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12137.259504] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [12139.667167] Lustre: Unmounted lustre-client [12171.965492] Key type lgssc unregistered [12172.193621] LNet: 259923:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12172.211449] LNetError: 259923:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12172.229520] LNet: Removed LNI 192.168.203.50@tcp [12172.972777] Key type .llcrypt unregistered [12172.981754] Key type ._llcrypt unregistered [12188.425608] Key type ._llcrypt registered [12188.429459] Key type .llcrypt registered [12189.768783] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 01:20:30 (1788844830) [12191.629823] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12191.661890] alg: No test for adler32 (adler32-zlib) [12192.718889] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [12192.941943] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [12194.609294] Key type lgssc registered [12195.549677] Lustre: Echo OBD driver; http://www.lustre.org/ [12281.355265] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12318.477612] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12325.378799] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12328.257850] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [12331.495476] Lustre: Unmounted lustre-client [12361.488468] Key type lgssc unregistered [12361.912488] LNet: 263461:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12361.927304] LNetError: 263461:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12361.956102] LNet: Removed LNI 192.168.203.50@tcp [12362.968335] Key type .llcrypt unregistered [12362.971766] Key type ._llcrypt unregistered [12381.305197] Key type ._llcrypt registered [12381.308844] Key type .llcrypt registered [12382.677411] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 01:23:43 (1788845023) [12385.102970] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12385.128034] alg: No test for adler32 (adler32-zlib) [12386.134115] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [12386.335808] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [12388.047178] Key type lgssc registered [12389.469927] Lustre: Echo OBD driver; http://www.lustre.org/ [12439.022434] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12445.030437] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12466.881138] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12468.582455] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [12473.848981] Lustre: lustre-MDT0000-mdc-ffff9882a0c2c800: Connection to lustre-MDT0000 (at 192.168.203.150@tcp) was lost; in progress operations using this service will wait for recovery to complete [12479.077055] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12479.109821] LustreError: 266618:0:(file.c:6167:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [12479.491176] LustreError: MGC192.168.203.150@tcp: Connection to MGS (at 192.168.203.150@tcp) was lost; in progress operations using this service will fail [12479.548599] Lustre: Unmounted lustre-client [12496.680229] Key type lgssc unregistered [12496.838894] LNet: 267028:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12496.850979] LNetError: 267028:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12496.868182] LNet: Removed LNI 192.168.203.50@tcp [12497.378097] Key type .llcrypt unregistered [12497.379883] Key type ._llcrypt unregistered [12513.098850] Key type ._llcrypt registered [12513.101391] Key type .llcrypt registered [12514.299520] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 01:25:55 (1788845155) [12516.722807] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12516.837616] alg: No test for adler32 (adler32-zlib) [12518.074229] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [12518.316414] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [12520.063526] Key type lgssc registered [12521.459674] Lustre: Echo OBD driver; http://www.lustre.org/ [12577.618896] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12583.839681] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12606.399697] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12609.440961] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [12614.632464] Lustre: lustre-MDT0001-mdc-ffff9882829a2000: Connection to lustre-MDT0001 (at 192.168.203.150@tcp) was lost; in progress operations using this service will wait for recovery to complete [12619.490438] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [12621.143540] Lustre: Unmounted lustre-client [12639.781737] Key type lgssc unregistered [12640.036731] LNet: 270607:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12640.051608] LNetError: 270607:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12640.068311] LNet: Removed LNI 192.168.203.50@tcp [12640.661781] Key type .llcrypt unregistered [12640.667412] Key type ._llcrypt unregistered [12654.215868] Key type ._llcrypt registered [12654.217638] Key type .llcrypt registered [12655.306760] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 01:28:16 (1788845296) [12671.891084] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12671.921889] alg: No test for adler32 (adler32-zlib) [12673.024413] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [12673.282821] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [12674.943675] Key type lgssc registered [12676.395600] Lustre: Echo OBD driver; http://www.lustre.org/ [12790.259397] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12792.956861] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [12834.286778] Lustre: lustre-MDT0001-mdc-ffff988286e0b800: Connection to lustre-MDT0001 (at 192.168.203.150@tcp) was lost; in progress operations using this service will wait for recovery to complete [12843.646984] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12854.017414] Lustre: Unmounted lustre-client [12882.561368] Key type lgssc unregistered [12882.819739] LNet: 276390:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12882.835124] LNetError: 276390:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12882.856771] LNet: Removed LNI 192.168.203.50@tcp [12883.546973] Key type .llcrypt unregistered [12883.549846] Key type ._llcrypt unregistered [12901.417939] Key type ._llcrypt registered [12901.420409] Key type .llcrypt registered [12902.794977] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 01:32:23 (1788845543) [12904.566369] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [12906.748922] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 01:32:27 (1788845547) [12908.554440] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [12910.439732] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 01:32:31 (1788845551) [12912.338675] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [12914.317908] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 01:32:35 (1788845555) [12916.420463] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [12918.103322] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 01:32:39 (1788845559) [12919.573953] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [12921.278298] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 01:32:42 (1788845562) [12931.400663] Key type .llcrypt unregistered [12931.404630] Key type ._llcrypt unregistered [12939.701765] Key type ._llcrypt registered [12939.703776] Key type .llcrypt registered [12940.164810] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12940.181324] alg: No test for adler32 (adler32-zlib) [12941.235385] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [12941.518384] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [12943.215212] Key type lgssc registered [12944.582458] Lustre: Echo OBD driver; http://www.lustre.org/ [13028.907643] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13034.816786] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13059.626978] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13061.643357] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [13064.556165] Lustre: Unmounted lustre-client [13102.484435] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 01:35:43 (1788845743) [13136.681744] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13143.211311] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13178.207774] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13184.066681] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [13186.113844] Lustre: Unmounted lustre-client [13230.306166] LNet: 1 local NIs in recovery (showing 1): 192.168.203.50@tcp [13233.517677] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 01:37:54 (1788845874) [13884.758888] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13891.353778] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13927.045711] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14033.852442] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 01:51:14 (1788846674) [14048.354891] Key type lgssc unregistered [14048.784386] LNet: 289911:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14048.792449] LNetError: 289911:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14048.820819] LNet: Removed LNI 192.168.203.50@tcp [14049.745192] Key type .llcrypt unregistered [14049.752467] Key type ._llcrypt unregistered [14176.857726] Key type ._llcrypt registered [14176.866222] Key type .llcrypt registered [14177.361334] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14177.378480] alg: No test for adler32 (adler32-zlib) [14178.680649] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [14179.023460] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [14180.729406] Key type lgssc registered [14182.323980] Lustre: Echo OBD driver; http://www.lustre.org/ [14276.110806] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14282.546556] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14311.630654] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14346.746314] Key type lgssc unregistered [14347.044559] LNet: 293602:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14347.056744] LNetError: 293602:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14347.079476] LNet: Removed LNI 192.168.203.50@tcp [14347.901678] Key type .llcrypt unregistered [14347.904656] Key type ._llcrypt unregistered [14384.330273] Key type ._llcrypt registered [14384.338746] Key type .llcrypt registered [14385.942872] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 01:57:06 (1788847026) [14408.846969] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14409.024088] alg: No test for adler32 (adler32-zlib) [14410.237512] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [14410.416254] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [14412.095579] Key type lgssc registered [14413.579927] Lustre: Echo OBD driver; http://www.lustre.org/ [14471.553746] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 01:58:32 (1788847112) [14482.164947] Key type lgssc unregistered [14482.444859] LNet: 297156:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14482.454287] LNetError: 297156:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14482.470644] LNet: Removed LNI 192.168.203.50@tcp [14483.069643] Key type .llcrypt unregistered [14483.074347] Key type ._llcrypt unregistered [14495.759969] Key type ._llcrypt registered [14495.763526] Key type .llcrypt registered [14496.104835] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14496.128983] alg: No test for adler32 (adler32-zlib) [14497.267247] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [14497.491266] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [14499.183188] Key type lgssc registered [14500.247331] Lustre: Echo OBD driver; http://www.lustre.org/ [14559.755581] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14565.962564] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14589.266693] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14876.768183] LNet: Added LNI 192.168.203.50@tcp50 [8/256/0/180] [14879.511372] LNet: Removed LNI 192.168.203.50@tcp [14881.865753] Lustre: 301564:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff9882a0c2e000: config has no valid NIDs, force dynamic NIDs [14883.144939] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [14883.311936] Lustre: Unmounted lustre-client [14885.979820] Key type lgssc unregistered [14886.572757] LNet: 301823:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14886.582070] LNetError: 301823:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14886.608407] LNet: Removed LNI 192.168.203.50@tcp50 [14887.348202] Key type .llcrypt unregistered [14887.354317] Key type ._llcrypt unregistered [14906.715957] Key type ._llcrypt registered [14906.719951] Key type .llcrypt registered [14907.135839] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14907.164760] alg: No test for adler32 (adler32-zlib) [14908.284416] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [14908.558559] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [14910.232234] Key type lgssc registered [14911.601464] Lustre: Echo OBD driver; http://www.lustre.org/ [14973.943822] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14980.451582] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14988.393299] LNet: Added LNI 192.168.203.50@tcp50 [8/256/0/180] [14989.090694] LNet: Removed LNI 192.168.203.50@tcp [14990.337108] Lustre: 304509:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff9882911a3800: config has no valid NIDs, force dynamic NIDs [14991.566139] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [14992.267491] Lustre: Unmounted lustre-client [15034.852731] Key type lgssc unregistered [15035.227648] LNet: 305378:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15035.234750] LNetError: 305378:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15035.253408] LNet: Removed LNI 192.168.203.50@tcp50 [15035.853448] Key type .llcrypt unregistered [15035.856066] Key type ._llcrypt unregistered [15046.379264] Key type ._llcrypt registered [15046.382640] Key type .llcrypt registered [15047.760249] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 02:08:08 (1788847688) [15068.240189] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15068.261490] alg: No test for adler32 (adler32-zlib) [15069.457462] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [15069.833879] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [15071.585219] Key type lgssc registered [15073.281765] Lustre: Echo OBD driver; http://www.lustre.org/ [15164.160818] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15170.789929] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15199.140234] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15243.853367] Key type lgssc unregistered [15244.215894] LNet: 310129:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15244.238708] LNetError: 310129:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15244.280972] LNet: Removed LNI 192.168.203.50@tcp [15245.520898] Key type .llcrypt unregistered [15245.530048] Key type ._llcrypt unregistered [15259.063537] Key type ._llcrypt registered [15259.066487] Key type .llcrypt registered [15260.603824] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 02:11:41 (1788847901) [15262.523789] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15262.566521] alg: No test for adler32 (adler32-zlib) [15263.678202] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [15263.866431] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [15265.576090] Key type lgssc registered [15266.661640] Lustre: Echo OBD driver; http://www.lustre.org/ [15322.201302] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15331.081345] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15357.431747] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15364.212491] Lustre: Mounted lustre-client - version 2.17.57_106_g45f4cc0 [15437.223691] Lustre: Mounted fs15246-client - version 2.17.57_106_g45f4cc0 [15453.967132] Lustre: Unmounted fs15246-client [15484.127135] Lustre: Unmounted lustre-client [15514.702696] Key type lgssc unregistered [15514.954883] LNet: 314995:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15514.965241] LNetError: 314995:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15514.983285] LNet: Removed LNI 192.168.203.50@tcp [15515.928359] Key type .llcrypt unregistered [15515.930512] Key type ._llcrypt unregistered [15528.694240] Key type ._llcrypt registered [15528.700285] Key type .llcrypt registered [15530.068089] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 02:16:10 (1788848170) [15532.315594] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15532.340086] alg: No test for adler32 (adler32-zlib) [15533.440191] Lustre: Lustre: Build Version: 2.17.57_106_g45f4cc0 [15533.631918] LNet: Added LNI 192.168.203.50@tcp [8/256/0/180] [15535.335165] Key type lgssc registered [15536.806454] Lustre: Echo OBD driver; http://www.lustre.org/ [15686.969092] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15695.557345] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15723.258984] Lustre: DEBUG MARKER: oleg350-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15839.818691] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 15584 sec ========= 02:21:20 (1788848480) [15841.921070] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 02:21:22 (1788848482) === [15845.743806] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 02:21:26 (1788848486) === [15887.774354] Key type lgssc unregistered [15888.023719] LNet: 324266:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15888.030692] LNetError: 324266:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15888.045395] LNet: Removed LNI 192.168.203.50@tcp [15888.694539] Key type .llcrypt unregistered [15888.697181] Key type ._llcrypt unregistered