[ 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 492240753 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524584K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001014] APIC: Switch to symmetric I/O mode setup [ 0.003074] x2apic enabled [ 0.004009] Switched APIC routing to physical x2apic. [ 0.005015] kvm-guest: setup PV IPIs [ 0.008601] ..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.010022] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.011013] pid_max: default: 32768 minimum: 301 [ 0.012174] LSM: Security Framework initializing [ 0.013056] Yama: becoming mindful. [ 0.014032] SELinux: Initializing. [ 0.015069] *** VALIDATE selinux *** [ 0.024578] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.029876] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.030202] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031138] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.033183] *** VALIDATE tmpfs *** [ 0.035331] *** VALIDATE proc *** [ 0.037149] *** VALIDATE cgroup *** [ 0.038011] *** VALIDATE cgroup2 *** [ 0.039350] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.040156] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.041005] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.042028] Spectre V2 : User space: Vulnerable [ 0.043006] Speculative Store Bypass: Vulnerable [ 0.046573] debug: unmapping init [mem 0xffffffff8d659000-0xffffffff8d660fff] [ 0.048873] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.049623] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.050016] ... version: 2 [ 0.051007] ... bit width: 48 [ 0.052006] ... generic registers: 4 [ 0.053007] ... value mask: 0000ffffffffffff [ 0.054006] ... max period: 00007fffffffffff [ 0.055006] ... fixed-purpose events: 3 [ 0.056006] ... event mask: 000000070000000f [ 0.058239] rcu: Hierarchical SRCU implementation. [ 0.060690] smp: Bringing up secondary CPUs ... [ 0.061537] x86: Booting SMP configuration: [ 0.062023] .... node #0, CPUs: #1 #2 #3 [ 0.074013] smp: Brought up 1 node, 4 CPUs [ 0.076066] smpboot: Max logical packages: 1 [ 0.077018] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.226653] node 0 deferred pages initialised in 146ms [ 0.229172] devtmpfs: initialized [ 0.230232] x86/mm: Memory block size: 128MB [ 0.233087] gcov: version magic: 0x41383552 [ 0.235302] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.236079] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.237265] pinctrl core: initialized pinctrl subsystem [ 0.238150] [ 0.238537] ************************************************************* [ 0.239011] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.240006] ** ** [ 0.241009] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.242009] ** ** [ 0.243006] ** This means that this kernel is built to expose internal ** [ 0.244010] ** IOMMU data structures, which may compromise security on ** [ 0.245010] ** your system. ** [ 0.246051] ** ** [ 0.247007] ** If you see this message and you are not debugging the ** [ 0.248007] ** kernel, report this immediately to your vendor! ** [ 0.249006] ** ** [ 0.250008] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.251008] ************************************************************* [ 0.252787] NET: Registered protocol family 16 [ 0.253504] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.254041] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.255041] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.256729] cpuidle: using governor menu [ 0.257613] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.258431] PCI: Using configuration type 1 for base access [ 0.259108] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.266005] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.267017] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.270025] cryptd: max_cpu_qlen set to 1000 [ 0.272284] ACPI: Added _OSI(Module Device) [ 0.273000] ACPI: Added _OSI(Processor Device) [ 0.273000] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.273000] ACPI: Added _OSI(Processor Aggregator Device) [ 0.277316] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.280549] ACPI: Interpreter enabled [ 0.282040] ACPI: PM: (supports S0 S3 S4 S5) [ 0.282952] ACPI: Using IOAPIC for interrupt routing [ 0.284080] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.286276] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.293874] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.295038] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.296013] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.298072] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.302170] acpiphp: Slot [2] registered [ 0.303073] acpiphp: Slot [5] registered [ 0.304032] acpiphp: Slot [6] registered [ 0.304869] acpiphp: Slot [3] registered [ 0.306072] acpiphp: Slot [4] registered [ 0.306921] acpiphp: Slot [7] registered [ 0.308050] acpiphp: Slot [8] registered [ 0.309081] acpiphp: Slot [9] registered [ 0.310065] acpiphp: Slot [10] registered [ 0.311060] acpiphp: Slot [11] registered [ 0.311935] acpiphp: Slot [12] registered [ 0.312061] acpiphp: Slot [13] registered [ 0.312845] acpiphp: Slot [14] registered [ 0.314059] acpiphp: Slot [15] registered [ 0.314914] acpiphp: Slot [16] registered [ 0.316056] acpiphp: Slot [17] registered [ 0.317015] acpiphp: Slot [18] registered [ 0.318000] acpiphp: Slot [19] registered [ 0.318000] acpiphp: Slot [20] registered [ 0.319102] acpiphp: Slot [21] registered [ 0.321139] acpiphp: Slot [22] registered [ 0.322082] acpiphp: Slot [23] registered [ 0.323114] acpiphp: Slot [24] registered [ 0.325079] acpiphp: Slot [25] registered [ 0.326021] acpiphp: Slot [26] registered [ 0.327123] acpiphp: Slot [27] registered [ 0.328099] acpiphp: Slot [28] registered [ 0.330082] acpiphp: Slot [29] registered [ 0.331113] acpiphp: Slot [30] registered [ 0.333089] acpiphp: Slot [31] registered [ 0.334050] PCI host bridge to bus 0000:00 [ 0.336012] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.338031] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.340015] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.342056] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.345018] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.347024] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.349185] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.352312] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.356252] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.365052] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.370044] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.371024] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.373022] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.375066] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.377661] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.380154] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.383052] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.386861] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.390828] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.403024] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.407013] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.413937] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.425020] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.433018] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.449042] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.459446] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.466057] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.472014] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.480014] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.488775] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.490370] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.491246] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.492227] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.493129] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.498085] iommu: Default domain type: Passthrough [ 0.500207] SCSI subsystem initialized [ 0.501085] ACPI: bus type USB registered [ 0.502081] usbcore: registered new interface driver usbfs [ 0.503043] usbcore: registered new interface driver hub [ 0.504057] usbcore: registered new device driver usb [ 0.505097] pps_core: LinuxPPS API ver. 1 registered [ 0.506006] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.508034] PTP clock support registered [ 0.510028] EDAC MC: Ver: 3.0.0 [ 0.511116] PCI: Using ACPI for IRQ routing [ 0.512643] NetLabel: Initializing [ 0.513006] NetLabel: domain hash size = 128 [ 0.513978] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.514099] NetLabel: unlabeled traffic allowed by default [ 0.516132] vgaarb: loaded [ 0.518043] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.519009] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.525283] clocksource: Switched to clocksource kvm-clock [ 0.640410] VFS: Disk quotas dquot_6.6.0 [ 0.641466] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.643191] *** VALIDATE ramfs *** [ 0.644028] *** VALIDATE hugetlbfs *** [ 0.645219] pnp: PnP ACPI init [ 0.647176] pnp: PnP ACPI: found 6 devices [ 0.662203] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.664556] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.666059] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.667510] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.669329] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.670889] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.672853] NET: Registered protocol family 2 [ 0.674838] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.679274] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.681992] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.686659] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.689303] TCP: Hash tables configured (established 65536 bind 65536) [ 0.691601] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.693734] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.695620] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.697643] NET: Registered protocol family 1 [ 0.699650] RPC: Registered named UNIX socket transport module. [ 0.701247] RPC: Registered udp transport module. [ 0.702520] RPC: Registered tcp transport module. [ 0.703940] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.705708] NET: Registered protocol family 44 [ 0.706932] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.708592] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.710265] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.711953] PCI: CLS 0 bytes, default 64 [ 0.713149] Unpacking initramfs... [ 2.352651] debug: unmapping init [mem 0xffff9f9bfcc64000-0xffff9f9bfffcffff] [ 2.356462] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.358475] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.361180] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.874215] Initialise system trusted keyrings [ 2.875991] Key type blacklist registered [ 2.879275] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.932152] zbud: loaded [ 2.944561] *** VALIDATE nfs *** [ 2.946888] *** VALIDATE nfs4 *** [ 2.951032] pstore: using deflate compression [ 2.986228] Platform Keyring initialized [ 3.418553] NET: Registered protocol family 38 [ 3.421454] Key type asymmetric registered [ 3.423259] Asymmetric key parser 'x509' registered [ 3.425338] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.428697] io scheduler mq-deadline registered [ 3.430670] io scheduler kyber registered [ 3.432543] io scheduler bfq registered [ 3.434307] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.437637] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.440933] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.444634] ACPI: Power Button [PWRF] [ 3.455505] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.475528] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.493933] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.523517] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.553185] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.557533] Non-volatile memory driver v1.3 [ 3.559330] Linux agpgart interface v0.103 [ 3.592214] virtio_blk virtio1: [vda] 146536 512-byte logical blocks (75.0 MB/71.6 MiB) [ 3.595195] vda: detected capacity change from 0 to 75026432 [ 3.614914] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.618036] vdb: detected capacity change from 0 to 1073741824 [ 3.630211] libphy: Fixed MDIO Bus: probed [ 3.635808] usbcore: registered new interface driver usbserial_generic [ 3.638140] usbserial: USB Serial support registered for generic [ 3.640313] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.644488] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.646232] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.648491] mousedev: PS/2 mouse device common for all mice [ 3.650601] rtc_cmos 00:05: RTC can wake from S4 [ 3.653206] rtc_cmos 00:05: registered as rtc0 [ 3.654934] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.657594] intel_pstate: CPU model not supported [ 3.662391] hid: raw HID events driver (C) Jiri Kosina [ 3.664013] usbcore: registered new interface driver usbhid [ 3.664457] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.669089] usbhid: USB HID core driver [ 3.669231] drop_monitor: Initializing network drop monitor service [ 3.669389] Initializing XFRM netlink socket [ 3.669914] NET: Registered protocol family 10 [ 3.676339] Segment Routing with IPv6 [ 3.681511] NET: Registered protocol family 17 [ 3.684333] mpls_gso: MPLS GSO support [ 3.686021] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.687771] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.705574] RAS: Correctable Errors collector initialized. [ 3.707584] AVX version of gcm_enc/dec engaged. [ 3.709442] AES CTR mode by8 optimization enabled [ 3.793673] sched_clock: Marking stable (3793654952, 0)->(4926227469, -1132572517) [ 3.797384] registered taskstats version 1 [ 3.799259] Loading compiled-in X.509 certificates [ 3.801823] zswap: loaded using pool lzo/zbud [ 3.830357] Key type big_key registered [ 3.843304] Key type encrypted registered [ 3.844907] ima: No TPM chip found, activating TPM-bypass! [ 3.847368] ima: Allocated hash algorithm: sha1 [ 3.849173] ima: No architecture policies found [ 3.851612] evm: Initialising EVM extended attributes: [ 3.853284] evm: security.selinux [ 3.854471] evm: security.ima [ 3.855521] evm: security.capability [ 3.857873] evm: HMAC attrs: 0x1 [ 3.862820] rtc_cmos 00:05: setting system clock to 2026-08-22 04:27:37 UTC (1787372857) [ 3.870579] debug: unmapping init [mem 0xffffffff8e603000-0xffffffff8e7fffff] [ 3.874698] debug: unmapping init [mem 0xffffffff8d382000-0xffffffff8d658fff] [ 3.885568] Write protecting the kernel read-only data: 28672k [ 3.890165] debug: unmapping init [mem 0xffffffff8ba03000-0xffffffff8bbfffff] [ 3.893018] debug: unmapping init [mem 0xffffffff8c314000-0xffffffff8c3fffff] [ 3.925948] 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.935510] systemd[1]: Detected virtualization kvm. [ 3.937573] systemd[1]: Detected architecture x86-64. [ 3.939693] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.972366] systemd[1]: No hostname configured. [ 3.975937] systemd[1]: Set hostname to . [ 3.980449] random: systemd: uninitialized urandom read (16 bytes read) [ 3.982492] systemd[1]: Initializing machine ID from random generator. [ 4.293581] random: systemd: uninitialized urandom read (16 bytes read) [ 4.297734] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 4.314617] random: systemd: uninitialized urandom read (16 bytes read) [ 4.318469] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 4.329847] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Timers. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Slices. [ OK ] Reached target Local File Systems. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Listening on Journal Socket. [ OK ] Reached target Sockets. Starting Setup Virtual Console... Starting Create list of required st…ce nodes for the current kernel... Starting Create Volatile Files and Directories... [ OK ] Started Memstrack Anylazing Service. Starting Journal Service... [ OK ] Reached target Swap. Starting Apply Kernel Variables... [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 5.326168] device-mapper: uevent: version 1.0.3 [ 5.328261] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target S[ 6.641576] virtio_net virtio0 ens2: renamed from eth0 ystem Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 7.075886] scsi host0: ata_piix [ 7.532349] scsi host1: ata_piix [ 7.533480] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 7.535370] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.685646] random: fast init done [ 12.383719] random: crng init done [ 12.388818] random: 7 urandom warning(s) missed due to ratelimiting [ 15.406824] dracut-initqueue[586]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 16.267235] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Paths. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 18.075113] printk: systemd: 26 output lines suppressed due to ratelimiting [ 18.507625] SELinux: Disabled at runtime. [ 18.571697] 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) [ 18.578602] systemd[1]: Detected virtualization kvm. [ 18.579996] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 19.745603] systemd[1]: initrd-switch-root.service: Succeeded. [ 19.749482] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 19.761329] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 19.767227] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 19.777866] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 19.788024] systemd[1]: Starting Journal Service... Starting Journal Service... [ 19.808146] systemd[1]: Created slice User and Session Slice. [ OK ] Created slice User and Session Slice. Mounting Huge Pages File System... [ OK ] Listening on RPCbind Server Activation Socket. Starting Remount Root and Kernel File Systems... Mounting Kernel Debug File System... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target rpc_pipefs.target. Mounting POSIX Message Queue File System... [ OK ] Listening on Process Core Dump Socket. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on udev Control Socket. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [ OK ] Reached target RPC Port Mapper. Activating swap /dev/disk/by-label/SWAP... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. [ 20.118164] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Created slice system-getty.slice. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. Starting Apply Kernel Variables... [ OK ] Stopped target Initrd File Systems. [ OK ] Reached target Local Encrypted Volumes. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Slices. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ OK ] Started Journal Service. [ OK ] Mounted Huge Pages File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ 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 udev Coldplug all Devices. [ 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. [ 21.442745] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 23.011776] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 23.030643] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 25.485691] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 26.449391] EDAC sbridge: Ver: 1.1.2 [* ] A start job is running for Configur…-only root support (7s / no limit) [** ] A start job is running for Configur…-only root support (8s / no limit) [*** ] A start job is running for Configur…-only root support (8s / no limit) [ *** ] A start job is running for Configur…-only root support (9s / no limit) [ *** ] A start job is running for Configur…-only root support (9s / no limit) [ ***] A start job is running for Configur…-only root support (9s / no limit)[ 29.823725] Key type dns_resolver registered [ **] A start job is running for Configur…only root support (10s / no limit) [ *] A start job is running for Configur…only root support (10s / no limit)[ 30.721753] NFS: Registering the id_resolver key type [ 30.724214] Key type id_resolver registered [ 30.726067] Key type id_legacy registered [ **] A start job is running for Configur…only root support (11s / no limit) [ ***] A start job is running for Configur…only root support (12s / no limit) [ *** ] A start job is running for Configur…only root support (12s / no limit) [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Mark the need to relabel after reboot. Starting Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started irqbalance daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... Starting Login Service... [ OK ] Reached target sshd-keygen.target. Starting Restore /run/initramfs on shutdown... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Network Manager Wait Online... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Started OpenSSH server daemon. [ OK ] Started Login Service. Starting Hostname Service... [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. [ 53.090655] hrtimer: interrupt took 5293543 ns Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg218-client login: [ 155.300317] libcfs: loading out-of-tree module taints kernel. [ 155.426130] Key type ._llcrypt registered [ 155.427277] Key type .llcrypt registered [ 156.122119] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 156.153862] alg: No test for adler32 (adler32-zlib) [ 157.593598] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [ 158.248352] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 159.959158] Key type lgssc registered [ 161.967927] Lustre: Echo OBD driver; http://www.lustre.org/ [ 327.988058] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 334.209834] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 352.821915] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing check_logdir /tmp/testlogs/ [ 353.766926] Lustre: lustre-OST0000-osc-ffff9f9c52126000: disconnect after 23s idle [ 360.196456] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing yml_node [ 364.724987] Lustre: DEBUG MARKER: Client: 2.17.57.46 [ 367.739994] Lustre: DEBUG MARKER: MDS: 2.17.57.46 [ 370.546031] Lustre: DEBUG MARKER: OSS: 2.17.57.46 [ 372.372206] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Sat Aug 22 00:33:44 EDT 2026 [ 393.312772] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 395.992487] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 399.685278] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 410.049171] Lustre: Unmounted lustre-client [ 537.465680] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 543.665164] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 570.063335] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 614.632794] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 00:37:46 (1787373466) [ 617.039771] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 618.615860] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 00:37:51 (1787373471) [ 653.072793] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 658.957635] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 681.589298] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 682.250879] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 690.709694] Lustre: Unmounted lustre-client [ 719.202467] Key type lgssc unregistered [ 719.517806] LNet: 15089:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 719.524213] LNetError: 15089:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 719.539220] LNet: Removed LNI 192.168.202.18@tcp [ 720.447172] Key type .llcrypt unregistered [ 720.449316] Key type ._llcrypt unregistered [ 739.037324] Key type ._llcrypt registered [ 739.043484] Key type .llcrypt registered [ 741.288735] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 00:39:52 (1787373592) [ 743.622681] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 743.704502] alg: No test for adler32 (adler32-zlib) [ 744.805580] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [ 745.075915] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 746.800360] Key type lgssc registered [ 748.305488] Lustre: Echo OBD driver; http://www.lustre.org/ [ 826.943070] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 918.778399] Lustre: Unmounted lustre-client [ 947.585304] Key type lgssc unregistered [ 947.862442] LNet: 18878:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 947.878132] LNetError: 18878:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 947.889480] LNet: Removed LNI 192.168.202.18@tcp [ 948.621658] Key type .llcrypt unregistered [ 948.623622] Key type ._llcrypt unregistered [ 959.816836] Key type ._llcrypt registered [ 959.820272] Key type .llcrypt registered [ 961.114366] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 00:43:33 (1787373813) [ 963.907371] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 963.963645] alg: No test for adler32 (adler32-zlib) [ 965.045887] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [ 965.296374] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 966.983218] Key type lgssc registered [ 968.540384] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1014.212960] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1019.793575] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1041.673394] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1044.513427] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 1055.835957] Lustre: Unmounted lustre-client [ 1084.607510] Key type lgssc unregistered [ 1084.906489] LNet: 22477:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1084.917528] LNetError: 22477:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1084.946920] LNet: Removed LNI 192.168.202.18@tcp [ 1085.705486] Key type .llcrypt unregistered [ 1085.707958] Key type ._llcrypt unregistered [ 1095.787858] Key type ._llcrypt registered [ 1095.790019] Key type .llcrypt registered [ 1097.115683] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 00:45:49 (1787373949) [ 1099.299217] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1099.458688] alg: No test for adler32 (adler32-zlib) [ 1100.651425] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [ 1100.947946] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 1102.664696] Key type lgssc registered [ 1103.862536] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1152.843446] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1159.435809] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1180.253474] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1183.037549] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 1192.394055] Lustre: Unmounted lustre-client [ 1220.424454] Key type lgssc unregistered [ 1220.644719] LNet: 25673:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1220.652271] LNetError: 25673:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1220.669423] LNet: Removed LNI 192.168.202.18@tcp [ 1221.167782] Key type .llcrypt unregistered [ 1221.170499] Key type ._llcrypt unregistered [ 1235.973350] Key type ._llcrypt registered [ 1235.974938] Key type .llcrypt registered [ 1237.100077] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 00:48:09 (1787374089) [ 1239.468447] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1239.502209] alg: No test for adler32 (adler32-zlib) [ 1240.781229] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [ 1241.072771] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 1242.735615] Key type lgssc registered [ 1243.884726] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1297.119675] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1302.768256] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1321.764920] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1323.423730] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 1328.619894] Lustre: lustre-MDT0000-mdc-ffff9f9c5000b000: Connection to lustre-MDT0000 (at 192.168.202.118@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1333.748494] Lustre: lustre-MDT0001-mdc-ffff9f9c5000b000: Connection to lustre-MDT0001 (at 192.168.202.118@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1342.018446] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1350.111984] Lustre: 26632:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787374187/real 1787374187] req@000000008875c221 x1874197577995648/t0(0) o400->MGC192.168.202.118@tcp@192.168.202.118@tcp:26/25 lens 224/224 e 0 to 1 dl 1787374203 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1350.137295] LustreError: MGC192.168.202.118@tcp: Connection to MGS (at 192.168.202.118@tcp) was lost; in progress operations using this service will fail [ 1352.346800] Lustre: Unmounted lustre-client [ 1369.444810] Key type lgssc unregistered [ 1369.654469] LNet: 29330:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1369.657759] LNetError: 29330:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1370.731649] LNet: Removed LNI 192.168.202.18@tcp [ 1371.425312] Key type .llcrypt unregistered [ 1371.427267] Key type ._llcrypt unregistered [ 1386.537335] Key type ._llcrypt registered [ 1386.539095] Key type .llcrypt registered [ 1387.885206] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 00:50:39 (1787374239) [ 1390.015828] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1390.223578] alg: No test for adler32 (adler32-zlib) [ 1391.348429] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [ 1391.616479] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 1393.295363] Key type lgssc registered [ 1394.429395] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1443.594873] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1449.269431] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1470.825440] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1473.727001] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 1479.159204] Lustre: lustre-OST0000-osc-ffff9f9c47f05000: Connection to lustre-OST0000 (at 192.168.202.118@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1485.569800] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1495.901591] Lustre: Unmounted lustre-client [ 1522.188292] Key type lgssc unregistered [ 1522.389864] LNet: 32942:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1522.396038] LNetError: 32942:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1523.434297] LNet: Removed LNI 192.168.202.18@tcp [ 1524.243793] Key type .llcrypt unregistered [ 1524.252355] Key type ._llcrypt unregistered [ 1538.681385] Key type ._llcrypt registered [ 1538.683970] Key type .llcrypt registered [ 1540.088144] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 00:53:12 (1787374392) [ 1542.365404] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1542.409841] alg: No test for adler32 (adler32-zlib) [ 1543.478969] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [ 1543.742027] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 1545.423182] Key type lgssc registered [ 1546.548719] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1592.418906] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1597.763637] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1617.105308] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1619.004126] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 1622.482217] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1626.050111] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1637.436511] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-08-22 0h54m49s [ 1639.226398] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1644.525050] Lustre: lustre-MDT0000-mdc-ffff9f9c44010000: Connection to lustre-MDT0000 (at 192.168.202.118@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1648.905316] LustreError: 36731:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff9f9c44010000: can't stat MDS #0: rc = -19 [ 1650.584989] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1656.820910] LustreError: MGC192.168.202.118@tcp: Connection to MGS (at 192.168.202.118@tcp) was lost; in progress operations using this service will fail [ 1656.838707] LustreError: lustre-MDT0000-mdc-ffff9f9c44010000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1656.861251] Lustre: Evicted from MGS (at 192.168.202.118@tcp) after server handle changed from 0x7dea64e363a5180c to 0x7dea64e363a51ab3 [ 1656.872339] Lustre: lustre-MDT0000-mdc-ffff9f9c44010000: Connection restored to 192.168.202.118@tcp (at 192.168.202.118@tcp) [ 1656.889034] Lustre: Skipped 1 previous similar message [ 1662.757743] Lustre: DEBUG MARKER: Wait for df (36731) ... [ 1664.166712] Lustre: DEBUG MARKER: done [ 1665.582159] Lustre: Unmounted lustre-client [ 1692.605736] Key type lgssc unregistered [ 1692.855037] LNet: 37751:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1692.858467] LNetError: 37751:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1692.876668] LNet: Removed LNI 192.168.202.18@tcp [ 1693.545555] Key type .llcrypt unregistered [ 1693.550614] Key type ._llcrypt unregistered [ 1722.195627] Key type ._llcrypt registered [ 1722.197433] Key type .llcrypt registered [ 1723.472736] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 00:56:15 (1787374575) [ 1725.914455] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1725.959944] alg: No test for adler32 (adler32-zlib) [ 1727.109803] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [ 1727.423308] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 1729.207399] Key type lgssc registered [ 1730.493678] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1775.818350] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1781.045189] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1800.476355] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1803.128911] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 1807.154293] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-08-22 0h57m39s [ 1808.666131] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1815.343588] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1818.123769] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1838.559181] Lustre: 41897:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787374671/real 1787374671] req@00000000d8b28650 x1874198088653056/t0(0) o101->lustre-MDT0000-mdc-ffff9f9c433b0000@192.168.202.118@tcp:12/10 lens 576/1152 e 0 to 1 dl 1787374691 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1838.580078] Lustre: lustre-MDT0000-mdc-ffff9f9c433b0000: Connection to lustre-MDT0000 (at 192.168.202.118@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1838.605972] Lustre: lustre-MDT0000-mdc-ffff9f9c433b0000: Connection restored to 192.168.202.118@tcp (at 192.168.202.118@tcp) [ 1859.480394] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1862.930694] Lustre: DEBUG MARKER: done [ 1868.185575] Lustre: Unmounted lustre-client [ 1906.527842] Key type lgssc unregistered [ 1906.728631] LNet: 42782:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1906.735227] LNetError: 42782:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1906.756593] LNet: Removed LNI 192.168.202.18@tcp [ 1907.502436] Key type .llcrypt unregistered [ 1907.504061] Key type ._llcrypt unregistered [ 1938.151467] Key type ._llcrypt registered [ 1938.154148] Key type .llcrypt registered [ 1939.676540] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 00:59:51 (1787374791) [ 1941.450589] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1943.235261] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 00:59:55 (1787374795) [ 1947.297473] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1947.318537] alg: No test for adler32 (adler32-zlib) [ 1948.491690] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [ 1948.889527] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 1950.663363] Key type lgssc registered [ 1952.127711] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1983.126841] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 01:00:35 (1787374835) [ 2017.975754] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2023.601644] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2042.887054] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2044.707079] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 2048.118639] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 2051.085426] Lustre: Unmounted lustre-client [ 2061.915448] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 2111.788863] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2118.810309] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2119.674425] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 2124.235409] Lustre: Unmounted lustre-client [ 2189.436579] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2194.559633] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2195.415374] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 2199.464032] Lustre: Unmounted lustre-client [ 2216.675848] Lustre: DEBUG MARKER: files compared the same [ 2229.766445] Key type lgssc unregistered [ 2230.010796] LNet: 50424:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2230.022728] LNetError: 50424:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2230.047620] LNet: Removed LNI 192.168.202.18@tcp [ 2230.726854] Key type .llcrypt unregistered [ 2230.729197] Key type ._llcrypt unregistered [ 2244.548818] Key type ._llcrypt registered [ 2244.551645] Key type .llcrypt registered [ 2246.103528] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 01:04:58 (1787375098) [ 2249.047726] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2249.060962] alg: No test for adler32 (adler32-zlib) [ 2250.197381] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [ 2250.469739] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 2252.103183] Key type lgssc registered [ 2253.003639] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2300.259159] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2305.463772] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2330.587265] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2332.458971] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 2337.702640] Lustre: Unmounted lustre-client [ 2364.417717] Key type lgssc unregistered [ 2364.674273] LNet: 54012:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2364.685645] LNetError: 54012:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2364.712903] LNet: Removed LNI 192.168.202.18@tcp [ 2365.389381] Key type .llcrypt unregistered [ 2365.391676] Key type ._llcrypt unregistered [ 2379.931784] Key type ._llcrypt registered [ 2379.934763] Key type .llcrypt registered [ 2381.036413] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 01:07:13 (1787375233) [ 2383.247208] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2383.289158] alg: No test for adler32 (adler32-zlib) [ 2384.293226] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [ 2384.546664] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 2386.231256] Key type lgssc registered [ 2387.302779] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2471.141135] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2504.355076] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2509.954615] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2537.316134] Key type lgssc unregistered [ 2537.547725] LNet: 57503:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2537.557970] LNetError: 57503:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2537.584408] LNet: Removed LNI 192.168.202.18@tcp [ 2538.205956] Key type .llcrypt unregistered [ 2538.207248] Key type ._llcrypt unregistered [ 2551.696153] Key type ._llcrypt registered [ 2551.701651] Key type .llcrypt registered [ 2553.181463] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 01:10:05 (1787375405) [ 2556.622955] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2556.691031] alg: No test for adler32 (adler32-zlib) [ 2557.818309] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [ 2558.164444] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 2559.912371] Key type lgssc registered [ 2561.540752] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2634.747340] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 2642.326655] Lustre: Unmounted lustre-client [ 2678.414825] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 01:12:10 (1787375530) [ 2790.822445] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2812.979422] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 2820.698638] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2820.708186] LustreError: 63981:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff9f9c44be5800: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 2820.729286] Lustre: 58487:0:(llite_lib.c:4342:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.202.118@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 2831.066631] Lustre: Unmounted lustre-client [ 2877.965834] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 01:15:29 (1787375729) [ 2889.413333] Key type lgssc unregistered [ 2889.824864] LNet: 65198:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2889.833766] LNetError: 65198:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2889.854618] LNet: Removed LNI 192.168.202.18@tcp [ 2890.489794] Key type .llcrypt unregistered [ 2890.501077] Key type ._llcrypt unregistered [ 2903.547730] Key type ._llcrypt registered [ 2903.558552] Key type .llcrypt registered [ 2903.863600] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2903.878174] alg: No test for adler32 (adler32-zlib) [ 2904.906986] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [ 2905.084065] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 2906.743442] Key type lgssc registered [ 2907.594530] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2952.880430] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2957.895085] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2978.240925] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2980.127232] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 2984.669669] Lustre: Unmounted lustre-client [ 3015.900885] Key type lgssc unregistered [ 3016.078821] LNet: 68213:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3016.087775] LNetError: 68213:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3017.131808] LNet: Removed LNI 192.168.202.18@tcp [ 3017.925699] Key type .llcrypt unregistered [ 3017.928246] Key type ._llcrypt unregistered [ 3030.383307] Key type ._llcrypt registered [ 3030.387259] Key type .llcrypt registered [ 3030.817270] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3030.842737] alg: No test for adler32 (adler32-zlib) [ 3031.902728] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [ 3032.170391] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 3033.847190] Key type lgssc registered [ 3035.044351] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3149.632837] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3155.373886] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3179.827111] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3181.610305] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 3184.896064] Lustre: Unmounted lustre-client [ 3218.122334] Key type lgssc unregistered [ 3218.389939] LNet: 71649:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3218.398532] LNetError: 71649:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3219.433764] LNet: Removed LNI 192.168.202.18@tcp [ 3220.299400] Key type .llcrypt unregistered [ 3220.308354] Key type ._llcrypt unregistered [ 3236.528457] Key type ._llcrypt registered [ 3236.530352] Key type .llcrypt registered [ 3237.789710] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 01:21:30 (1787376090) [ 3240.631857] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3240.706302] alg: No test for adler32 (adler32-zlib) [ 3242.020681] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [ 3242.370993] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 3244.159226] Key type lgssc registered [ 3245.781559] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3299.647407] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3305.943782] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3327.765604] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3330.639905] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 3339.363820] Lustre: Unmounted lustre-client [ 3340.010816] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 3344.443886] Lustre: Unmounted lustre-client [ 3374.232322] Key type lgssc unregistered [ 3374.640431] LNet: 75340:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3374.657592] LNetError: 75340:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3374.691381] LNet: Removed LNI 192.168.202.18@tcp [ 3375.757796] Key type .llcrypt unregistered [ 3375.770055] Key type ._llcrypt unregistered [ 3388.455242] Key type ._llcrypt registered [ 3388.464610] Key type .llcrypt registered [ 3389.188763] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3389.200809] alg: No test for adler32 (adler32-zlib) [ 3390.218754] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [ 3390.584768] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 3392.321659] Key type lgssc registered [ 3393.945511] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3454.288371] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3461.346638] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3485.163250] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3497.607274] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 3502.289355] Lustre: Unmounted lustre-client [ 3535.543602] Key type lgssc unregistered [ 3535.796851] LNet: 78384:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3535.804221] LNetError: 78384:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3535.829744] LNet: Removed LNI 192.168.202.18@tcp [ 3536.530890] Key type .llcrypt unregistered [ 3536.534483] Key type ._llcrypt unregistered [ 3555.241344] Key type ._llcrypt registered [ 3555.245406] Key type .llcrypt registered [ 3557.319669] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 01:26:48 (1787376408) [ 3559.826677] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3559.901916] alg: No test for adler32 (adler32-zlib) [ 3561.079967] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [ 3561.385616] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 3563.183242] Key type lgssc registered [ 3564.974384] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3623.552773] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3630.456426] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3654.942611] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3666.027405] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 3677.822272] Lustre: lustre: root_squash is set to 0:0 [ 3677.874430] Lustre: lustre: nosquash_nids is cleared [ 3689.521593] Lustre: lustre: root_squash is set to 500:500 [ 3701.833778] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.202.18@tcp 192.168.0.[2,10]@tcp [ 3716.659676] Lustre: lustre: nosquash_nids set to 192.168.202.18/24@tcp [ 3720.060025] Lustre: Unmounted lustre-client [ 3755.636823] Key type lgssc unregistered [ 3756.033086] LNet: 82394:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3756.048051] LNetError: 82394:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3756.082389] LNet: Removed LNI 192.168.202.18@tcp [ 3756.718240] Key type .llcrypt unregistered [ 3756.725556] Key type ._llcrypt unregistered [ 3774.432439] Key type ._llcrypt registered [ 3774.442228] Key type .llcrypt registered [ 3776.031432] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 01:30:27 (1787376627) [ 3779.228204] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3782.145812] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 01:30:33 (1787376633) [ 3785.119601] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3785.196399] alg: No test for adler32 (adler32-zlib) [ 3786.434223] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [ 3786.874823] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 3788.610131] Key type lgssc registered [ 3790.148956] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3847.519926] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3856.185619] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3882.975739] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3884.728211] Lustre: lustre: root_squash is set to 500:500 [ 3884.742516] Lustre: lustre: nosquash_nids set to 192.168.202.18/24@tcp [ 3890.331645] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 3899.147686] Lustre: Unmounted lustre-client [ 3939.081862] Key type lgssc unregistered [ 3939.444478] LNet: 86368:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3939.452277] LNetError: 86368:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3939.469079] LNet: Removed LNI 192.168.202.18@tcp [ 3940.439132] Key type .llcrypt unregistered [ 3940.446624] Key type ._llcrypt unregistered [ 3958.326984] Key type ._llcrypt registered [ 3958.329407] Key type .llcrypt registered [ 3960.069986] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 3961.625746] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 01:33:33 (1787376813) [ 3981.666632] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3981.795257] alg: No test for adler32 (adler32-zlib) [ 3983.035431] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [ 3983.319918] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 3985.127197] Key type lgssc registered [ 3986.598711] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4069.755978] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4075.697388] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4098.313383] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4165.749759] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4171.221760] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4189.526587] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4200.560806] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 4231.375806] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9f9c582e3800.ost_server_uuid 50 [ 4233.191930] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9f9c582e3800.ost_server_uuid in FULL state after 0 sec [ 4233.890420] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 4238.141122] Lustre: Unmounted lustre-client [ 4240.922880] Lustre: Unmounted lustre-client [ 4289.718981] Key type lgssc unregistered [ 4290.104797] LNet: 94042:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4290.119828] LNetError: 94042:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4290.156623] LNet: Removed LNI 192.168.202.18@tcp [ 4291.112237] Key type .llcrypt unregistered [ 4291.114374] Key type ._llcrypt unregistered [ 4322.979226] Key type ._llcrypt registered [ 4322.982420] Key type .llcrypt registered [ 4324.376751] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 01:39:36 (1787377176) [ 4343.179396] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4343.220630] alg: No test for adler32 (adler32-zlib) [ 4344.376606] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [ 4344.843477] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 4346.628803] Key type lgssc registered [ 4348.009674] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4435.601942] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4441.941253] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4465.146352] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4466.885648] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 4487.647154] Lustre: 95406:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787377325/real 1787377325] req@00000000cddee578 x1874200832775296/t0(0) o400->lustre-OST0000-osc-ffff9f9c6046f800@192.168.202.118@tcp:28/4 lens 224/224 e 0 to 1 dl 1787377341 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4487.667817] Lustre: lustre-OST0000-osc-ffff9f9c6046f800: Connection to lustre-OST0000 (at 192.168.202.118@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4493.791406] Lustre: 95407:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787377331/real 1787377331] req@000000007b1989d9 x1874200832775808/t0(0) o400->lustre-OST0000-osc-ffff9f9c6046f800@192.168.202.118@tcp:28/4 lens 224/224 e 0 to 1 dl 1787377347 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4497.957923] Lustre: lustre-OST0000-osc-ffff9f9c6046f800: Connection restored to 192.168.202.118@tcp (at 192.168.202.118@tcp) [ 4499.039422] Lustre: 95406:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787377336/real 1787377336] req@00000000999b1565 x1874200832776320/t0(0) o400->lustre-OST0000-osc-ffff9f9c6046f800@192.168.202.118@tcp:28/4 lens 224/224 e 0 to 1 dl 1787377352 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4503.016949] Lustre: lustre-MDT0000-mdc-ffff9f9c6046f800: Connection to lustre-MDT0000 (at 192.168.202.118@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4504.032545] Lustre: 95407:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787377341/real 1787377341] req@00000000cdcb5e45 x1874200832776832/t0(0) o400->lustre-OST0000-osc-ffff9f9c6046f800@192.168.202.118@tcp:28/4 lens 224/224 e 0 to 1 dl 1787377357 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4519.263350] Lustre: 95408:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787377356/real 1787377356] req@00000000a5650b93 x1874200832778752/t0(0) o400->MGC192.168.202.118@tcp@192.168.202.118@tcp:26/25 lens 224/224 e 0 to 1 dl 1787377372 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4519.300424] LustreError: MGC192.168.202.118@tcp: Connection to MGS (at 192.168.202.118@tcp) was lost; in progress operations using this service will fail [ 4525.783051] Lustre: lustre-MDT0000-mdc-ffff9f9c6046f800: Connection restored to 192.168.202.118@tcp (at 192.168.202.118@tcp) [ 4528.563542] Lustre: Evicted from MGS (at 192.168.202.118@tcp) after server handle changed from 0x2f41275d545f4247 to 0x2f41275d545f4661 [ 4528.571710] Lustre: MGC192.168.202.118@tcp: Connection restored to 192.168.202.118@tcp (at 192.168.202.118@tcp) [ 4532.043151] Lustre: Unmounted lustre-client [ 4559.723213] Key type lgssc unregistered [ 4560.199701] LNet: 99712:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4560.225455] LNetError: 99712:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4560.265730] LNet: Removed LNI 192.168.202.18@tcp [ 4561.194930] Key type .llcrypt unregistered [ 4561.196941] Key type ._llcrypt unregistered [ 4579.712600] Key type ._llcrypt registered [ 4579.714887] Key type .llcrypt registered [ 4581.646761] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 01:43:53 (1787377433) [ 4597.755731] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4597.766475] alg: No test for adler32 (adler32-zlib) [ 4598.922835] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [ 4599.226904] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 4600.999241] Key type lgssc registered [ 4602.543194] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4645.792538] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4745.916821] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4752.714812] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4776.093815] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4778.893783] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 4781.203264] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 4783.010681] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 01:47:15 (1787377635) [ 5000.349347] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5010.727060] Lustre: Unmounted lustre-client [ 5151.020958] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5156.614024] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5180.913607] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5222.512830] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 01:54:34 (1787378074) [ 5321.339510] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5328.320147] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5353.119450] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5353.951404] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 5358.441663] Lustre: Unmounted lustre-client [ 5389.903726] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 01:57:22 (1787378242) [ 5487.475888] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5494.206722] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5518.364454] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5519.114235] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 5523.278853] Lustre: Unmounted lustre-client [ 5562.642854] Key type lgssc unregistered [ 5563.061826] LNet: 122750:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5563.076686] LNetError: 122750:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5563.116137] LNet: Removed LNI 192.168.202.18@tcp [ 5563.839233] Key type .llcrypt unregistered [ 5563.841280] Key type ._llcrypt unregistered [ 5583.226465] Key type ._llcrypt registered [ 5583.227745] Key type .llcrypt registered [ 5585.057329] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 02:00:36 (1787378436) [ 5587.642671] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5587.676179] alg: No test for adler32 (adler32-zlib) [ 5588.944536] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [ 5589.260674] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 5590.911317] Key type lgssc registered [ 5592.334259] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5651.999415] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5658.110504] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5679.205752] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5690.409926] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 5704.721308] Lustre: Unmounted lustre-client [ 5734.110367] Key type lgssc unregistered [ 5734.504307] LNet: 126365:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5734.512346] LNetError: 126365:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5734.536397] LNet: Removed LNI 192.168.202.18@tcp [ 5735.324585] Key type .llcrypt unregistered [ 5735.327940] Key type ._llcrypt unregistered [ 5752.299390] Key type ._llcrypt registered [ 5752.301218] Key type .llcrypt registered [ 5753.767653] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 02:03:25 (1787378605) [ 5756.307809] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5756.344788] alg: No test for adler32 (adler32-zlib) [ 5757.547727] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [ 5757.824791] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 5759.559236] Key type lgssc registered [ 5760.788431] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5815.212733] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5822.152579] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5845.090869] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5846.932188] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 5852.157896] Lustre: lustre-OST0000-osc-ffff9f9c50ac4000: Connection to lustre-OST0000 (at 192.168.202.118@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5865.700368] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff9f9c50ac4000.ost_server_uuid 50 [ 5867.506284] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 5878.577500] Lustre: Unmounted lustre-client [ 5901.122936] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 02:05:53 (1787378753) [ 5932.234111] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5938.518687] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5960.759872] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5983.980971] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5984.718924] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 5989.934633] Lustre: lustre-OST0000-osc-ffff9f9c45716000: Connection to lustre-OST0000 (at 192.168.202.118@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6016.140514] Lustre: Unmounted lustre-client [ 6064.558227] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 02:08:36 (1787378916) [ 6101.799764] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6109.036146] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6131.035279] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6154.349340] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6155.012643] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 6160.363544] Lustre: lustre-OST0000-osc-ffff9f9c466db000: Connection to lustre-OST0000 (at 192.168.202.118@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6177.342504] Lustre: Unmounted lustre-client [ 6225.561568] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 02:11:17 (1787379077) [ 6325.226288] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6330.957233] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6357.522387] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6427.807956] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6434.163871] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6454.986437] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6485.385047] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 6529.630199] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6531.212208] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6533.453673] Lustre: Unmounted lustre-client [ 6563.170095] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 02:16:55 (1787379415) [ 6594.332187] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6600.759781] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6624.416825] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6663.598580] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6700.754545] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 6746.204803] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6748.419934] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6761.153531] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6771.531071] Lustre: Unmounted lustre-client [ 6827.714545] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 02:21:19 (1787379679) [ 6864.114873] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6870.354283] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6890.846225] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6891.425679] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 6915.100750] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6917.062087] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6933.863752] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9f9c51515000.ost_server_uuid 50 [ 6935.618228] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9f9c51515000.ost_server_uuid in IDLE state after 0 sec [ 6938.134913] Lustre: Unmounted lustre-client [ 6938.499462] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 6938.509038] Lustre: Skipped 3 previous similar messages [ 6938.601498] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 6939.617216] LustreError: 149773:0:(sec.c:403:import_sec_validate_get()) import 00000000d483a92f (NEW) with no sec [ 6999.047306] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 02:24:11 (1787379851) [ 7038.274213] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7046.311091] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7074.454563] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7098.998784] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7099.551169] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 7099.670134] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 7111.646160] Lustre: Unmounted lustre-client [ 7111.652271] Lustre: Skipped 1 previous similar message [ 7148.500988] Key type lgssc unregistered [ 7148.953139] LNet: 153184:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7148.969050] LNetError: 153184:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7148.998200] LNet: Removed LNI 192.168.202.18@tcp [ 7150.167502] Key type .llcrypt unregistered [ 7150.174500] Key type ._llcrypt unregistered [ 7168.546288] Key type ._llcrypt registered [ 7168.552772] Key type .llcrypt registered [ 7170.404386] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 02:27:01 (1787380021) [ 7171.952039] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7172.018302] alg: No test for adler32 (adler32-zlib) [ 7173.195895] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [ 7173.583369] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 7175.282523] Key type lgssc registered [ 7176.438528] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7239.355649] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7246.216982] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7272.483846] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7297.133368] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7298.835594] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7300.052152] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 7302.821062] LustreError: lustre-MDT0001-mdc-ffff9f9c433f4800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 7302.856035] Lustre: lustre-MDT0001-mdc-ffff9f9c433f4800: Connection restored to 192.168.202.118@tcp (at 192.168.202.118@tcp) [ 7310.024863] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7311.818693] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7336.457509] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7341.683483] Lustre: Unmounted lustre-client [ 7375.573761] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 02:30:27 (1787380227) [ 7479.867368] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7485.646398] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7513.031563] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7513.822675] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 7544.172294] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7545.873475] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7558.696236] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7569.073418] Lustre: Unmounted lustre-client [ 7614.738158] Key type lgssc unregistered [ 7615.054555] LNet: 162792:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7615.059599] LNetError: 162792:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7615.074588] LNet: Removed LNI 192.168.202.18@tcp [ 7615.621411] Key type .llcrypt unregistered [ 7615.622945] Key type ._llcrypt unregistered [ 7651.932593] Key type ._llcrypt registered [ 7651.935423] Key type .llcrypt registered [ 7653.560411] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 02:35:05 (1787380505) [ 7656.612714] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7656.678287] alg: No test for adler32 (adler32-zlib) [ 7657.958911] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [ 7658.424615] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 7660.307182] Key type lgssc registered [ 7661.863732] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7719.309632] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7726.955840] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7752.663950] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7755.568609] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 7805.255863] Lustre: Unmounted lustre-client [ 7849.488951] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7850.302459] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 7887.597039] Lustre: Unmounted lustre-client [ 7921.400263] Key type lgssc unregistered [ 7921.893514] LNet: 167241:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7921.925047] LNetError: 167241:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7921.952826] LNet: Removed LNI 192.168.202.18@tcp [ 7923.498219] Key type .llcrypt unregistered [ 7923.503722] Key type ._llcrypt unregistered [ 7949.173751] Key type ._llcrypt registered [ 7949.177108] Key type .llcrypt registered [ 7950.827733] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 02:40:02 (1787380802) [ 7953.539100] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7953.597723] alg: No test for adler32 (adler32-zlib) [ 7954.936421] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [ 7955.223084] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 7956.895236] Key type lgssc registered [ 7958.631569] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8024.650130] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8031.630516] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8061.445386] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8064.857606] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 8085.845490] Lustre: Unmounted lustre-client [ 8118.556537] Key type lgssc unregistered [ 8118.902722] LNet: 171003:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8118.910916] LNetError: 171003:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8118.929058] LNet: Removed LNI 192.168.202.18@tcp [ 8119.689838] Key type .llcrypt unregistered [ 8119.696468] Key type ._llcrypt unregistered [ 8135.816085] Key type ._llcrypt registered [ 8135.834439] Key type .llcrypt registered [ 8136.649758] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8136.681380] alg: No test for adler32 (adler32-zlib) [ 8137.896673] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [ 8138.321607] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 8140.087288] Key type lgssc registered [ 8141.757477] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8201.186560] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8209.565988] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8236.345589] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8242.767199] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 8249.544176] Lustre: Unmounted lustre-client [ 8279.115058] Key type lgssc unregistered [ 8279.431329] LNet: 174074:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8279.441593] LNetError: 174074:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8279.453488] LNet: Removed LNI 192.168.202.18@tcp [ 8280.337038] Key type .llcrypt unregistered [ 8280.339628] Key type ._llcrypt unregistered [ 8295.757463] Key type ._llcrypt registered [ 8295.759513] Key type .llcrypt registered [ 8297.253602] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 02:45:49 (1787381149) [ 8299.884900] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8299.949263] alg: No test for adler32 (adler32-zlib) [ 8301.134817] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [ 8301.442277] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 8303.159898] Key type lgssc registered [ 8305.022590] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8368.763440] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8377.323977] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8402.125769] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8408.098315] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 8432.141054] Lustre: Unmounted lustre-client [ 8463.918294] Key type lgssc unregistered [ 8464.202683] LNet: 177830:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8464.214689] LNetError: 177830:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8464.231779] LNet: Removed LNI 192.168.202.18@tcp [ 8465.029890] Key type .llcrypt unregistered [ 8465.032545] Key type ._llcrypt unregistered [ 8481.019591] Key type ._llcrypt registered [ 8481.029440] Key type .llcrypt registered [ 8481.873299] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8481.919037] alg: No test for adler32 (adler32-zlib) [ 8483.191952] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [ 8483.655641] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 8485.353450] Key type lgssc registered [ 8486.674392] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8547.968794] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8554.801349] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8580.883183] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8586.882836] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 8596.469269] Lustre: Unmounted lustre-client [ 8627.372522] Key type lgssc unregistered [ 8627.649035] LNet: 180899:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8627.659187] LNetError: 180899:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8627.684186] LNet: Removed LNI 192.168.202.18@tcp [ 8628.623737] Key type .llcrypt unregistered [ 8628.626196] Key type ._llcrypt unregistered [ 8647.856547] Key type ._llcrypt registered [ 8647.861527] Key type .llcrypt registered [ 8649.634838] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 02:51:41 (1787381501) [ 8677.140609] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8677.245532] alg: No test for adler32 (adler32-zlib) [ 8678.418711] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [ 8678.859931] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 8680.647180] Key type lgssc registered [ 8682.297944] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8772.019865] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8778.748653] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8805.923561] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8849.268936] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 02:55:01 (1787381701) [ 8891.189711] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8899.106083] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8925.270467] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8932.407690] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 8937.771545] Lustre: Unmounted lustre-client [ 8964.774925] Key type lgssc unregistered [ 8965.029728] LNet: 188005:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8965.038824] LNetError: 188005:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8965.063312] LNet: Removed LNI 192.168.202.18@tcp [ 8965.696255] Key type .llcrypt unregistered [ 8965.698302] Key type ._llcrypt unregistered [ 8983.670346] Key type ._llcrypt registered [ 8983.672227] Key type .llcrypt registered [ 8985.736808] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 8987.710800] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 02:57:19 (1787381839) [ 9009.426726] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9009.439808] alg: No test for adler32 (adler32-zlib) [ 9010.569668] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [ 9010.758271] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 9012.415196] Key type lgssc registered [ 9013.848552] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9107.352777] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9113.158581] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9139.316758] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9159.994472] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9161.802714] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 9185.288472] Lustre: Unmounted lustre-client [ 9311.268765] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9316.933266] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9341.335742] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9382.775175] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 03:03:55 (1787382235) [ 9384.159206] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 9385.812524] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 03:03:58 (1787382238) [ 9499.881953] Key type lgssc unregistered [ 9500.126058] LNet: 199459:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9500.140203] LNetError: 199459:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9500.170571] LNet: Removed LNI 192.168.202.18@tcp [ 9500.878879] Key type .llcrypt unregistered [ 9500.881624] Key type ._llcrypt unregistered [ 9517.407962] Key type ._llcrypt registered [ 9517.411747] Key type .llcrypt registered [ 9519.229913] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 03:06:10 (1787382370) [ 9559.099661] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9559.129360] alg: No test for adler32 (adler32-zlib) [ 9560.173572] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [ 9560.378193] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 9562.047235] Key type lgssc registered [ 9563.161382] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9608.630769] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9615.221968] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9651.270703] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9680.830921] Key type lgssc unregistered [ 9681.187328] LNet: 203194:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9681.211117] LNetError: 203194:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9681.234052] LNet: Removed LNI 192.168.202.18@tcp [ 9682.333374] Key type .llcrypt unregistered [ 9682.338593] Key type ._llcrypt unregistered [ 9701.501405] Key type ._llcrypt registered [ 9701.507187] Key type .llcrypt registered [ 9703.120074] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 03:09:14 (1787382554) [ 9706.835560] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9706.948703] alg: No test for adler32 (adler32-zlib) [ 9708.168350] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [ 9708.482110] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 9710.272272] Key type lgssc registered [ 9711.398277] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9764.395987] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9770.765495] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9793.918671] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9795.630513] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [ 9810.941598] Lustre: lustre-MDT0000-mdc-ffff9f9c45906000: Connection to lustre-MDT0000 (at 192.168.202.118@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9821.169099] Lustre: lustre-MDT0001-mdc-ffff9f9c45906000: Connection to lustre-MDT0001 (at 192.168.202.118@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9831.392245] Lustre: 204187:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787382669/real 1787382669] req@000000004387f29c x1874206457414272/t0(0) o400->MGC192.168.202.118@tcp@192.168.202.118@tcp:26/25 lens 224/224 e 0 to 1 dl 1787382685 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9831.419908] LustreError: MGC192.168.202.118@tcp: Connection to MGS (at 192.168.202.118@tcp) was lost; in progress operations using this service will fail [ 9857.024727] Lustre: Evicted from MGS (at 192.168.202.118@tcp) after server handle changed from 0xe9e1d3ab1d3e0685 to 0xe9e1d3ab1d3e21cf [ 9857.036849] Lustre: MGC192.168.202.118@tcp: Connection restored to 192.168.202.118@tcp (at 192.168.202.118@tcp) [ 9886.701855] LustreError: lustre-MDT0000-mdc-ffff9f9c45906000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 9886.807934] Lustre: lustre-MDT0000-mdc-ffff9f9c45906000: Connection restored to 192.168.202.118@tcp (at 192.168.202.118@tcp) [ 9893.109894] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9895.705345] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9903.008534] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9904.797398] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9908.569593] Lustre: Unmounted lustre-client [ 9945.045712] Key type lgssc unregistered [ 9945.287933] LNet: 208144:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9945.295779] LNetError: 208144:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9945.308448] LNet: Removed LNI 192.168.202.18@tcp [ 9946.167857] Key type .llcrypt unregistered [ 9946.170309] Key type ._llcrypt unregistered [ 9967.861717] Key type ._llcrypt registered [ 9967.863357] Key type .llcrypt registered [ 9969.355976] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 03:13:41 (1787382821) [ 9972.725985] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9972.818170] alg: No test for adler32 (adler32-zlib) [ 9974.168815] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [ 9974.514370] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [ 9976.295193] Key type lgssc registered [ 9977.793479] Lustre: Echo OBD driver; http://www.lustre.org/ [10026.950988] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10033.493838] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10095.197659] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10102.144278] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10143.183462] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10167.802461] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10213.735909] Key type lgssc unregistered [10213.961779] LNet: 213445:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10213.966800] LNetError: 213445:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10213.979766] LNet: Removed LNI 192.168.202.18@tcp [10214.708536] Key type .llcrypt unregistered [10214.710178] Key type ._llcrypt unregistered [10256.108786] Key type ._llcrypt registered [10256.117207] Key type .llcrypt registered [10257.449660] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 03:18:29 (1787383109) [10293.506710] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10293.568214] alg: No test for adler32 (adler32-zlib) [10294.635500] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [10294.808547] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [10296.527203] Key type lgssc registered [10297.700965] Lustre: Echo OBD driver; http://www.lustre.org/ [10380.603284] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10386.639592] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10408.789983] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10451.049477] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 03:21:43 (1787383303) [10458.684551] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 03:21:51 (1787383311) [10493.747305] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10500.400768] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10522.554893] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10525.449391] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [10534.680936] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [10537.225333] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [10539.073597] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [10540.657579] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [10546.153571] Lustre: lustre-MDT0000-mdc-ffff9f9c50ac7000: Connection to lustre-MDT0000 (at 192.168.202.118@tcp) was lost; in progress operations using this service will wait for recovery to complete [10561.505296] Lustre: 215341:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787383399/real 1787383399] req@000000005a4d4e0a x1874207071807616/t0(0) o400->MGC192.168.202.118@tcp@192.168.202.118@tcp:26/25 lens 224/224 e 0 to 1 dl 1787383415 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10561.546115] LustreError: MGC192.168.202.118@tcp: Connection to MGS (at 192.168.202.118@tcp) was lost; in progress operations using this service will fail [10571.770189] Lustre: Evicted from MGS (at 192.168.202.118@tcp) after server handle changed from 0xb1c3b6455d16d694 to 0xb1c3b6455d16d80e [10571.784309] Lustre: MGC192.168.202.118@tcp: Connection restored to 192.168.202.118@tcp (at 192.168.202.118@tcp) [10578.665973] Lustre: lustre-MDT0000-mdc-ffff9f9c50ac7000: Connection restored to 192.168.202.118@tcp (at 192.168.202.118@tcp) [10589.164500] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10590.655250] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10594.009984] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [10597.356383] Lustre: lustre-MDT0000-mdc-ffff9f9c50ac7000: Connection to lustre-MDT0000 (at 192.168.202.118@tcp) was lost; in progress operations using this service will wait for recovery to complete [10612.703243] Lustre: 215339:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787383450/real 1787383450] req@00000000b31334c1 x1874207071816576/t0(0) o400->MGC192.168.202.118@tcp@192.168.202.118@tcp:26/25 lens 224/224 e 0 to 1 dl 1787383466 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10612.744207] LustreError: MGC192.168.202.118@tcp: Connection to MGS (at 192.168.202.118@tcp) was lost; in progress operations using this service will fail [10622.952038] Lustre: Evicted from MGS (at 192.168.202.118@tcp) after server handle changed from 0xb1c3b6455d16d80e to 0xb1c3b6455d16db25 [10622.965047] Lustre: MGC192.168.202.118@tcp: Connection restored to 192.168.202.118@tcp (at 192.168.202.118@tcp) [10631.392794] Lustre: lustre-MDT0000-mdc-ffff9f9c50ac7000: Connection restored to 192.168.202.118@tcp (at 192.168.202.118@tcp) [10639.722438] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10641.504129] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10644.783977] Lustre: Unmounted lustre-client [10713.044931] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10719.156407] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10739.368312] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10740.017970] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [10747.140379] Lustre: Unmounted lustre-client [10780.295766] Key type lgssc unregistered [10780.684185] LNet: 225925:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10780.709116] LNetError: 225925:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10780.737946] LNet: Removed LNI 192.168.202.18@tcp [10781.617138] Key type .llcrypt unregistered [10781.619102] Key type ._llcrypt unregistered [10807.589873] Key type ._llcrypt registered [10807.593207] Key type .llcrypt registered [10809.614987] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 03:27:41 (1787383661) [10815.403499] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10815.426937] alg: No test for adler32 (adler32-zlib) [10816.738609] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [10817.111784] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [10818.791312] Key type lgssc registered [10820.411518] Lustre: Echo OBD driver; http://www.lustre.org/ [10877.028221] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10883.183376] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10905.290148] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10907.004543] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [10918.000648] Lustre: Unmounted lustre-client [10987.783532] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10993.421367] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11012.508119] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11013.316991] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [11022.458330] Lustre: Unmounted lustre-client [11100.385621] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11108.922774] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11130.573278] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11141.696920] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [11144.080237] Lustre: Unmounted lustre-client [11173.975916] Key type lgssc unregistered [11174.411023] LNet: 232980:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11174.421709] LNetError: 232980:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11174.444655] LNet: Removed LNI 192.168.202.18@tcp [11175.206354] Key type .llcrypt unregistered [11175.210447] Key type ._llcrypt unregistered [11192.921388] Key type ._llcrypt registered [11192.926181] Key type .llcrypt registered [11194.516898] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 03:34:06 (1787384046) [11198.448824] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11198.482467] alg: No test for adler32 (adler32-zlib) [11199.684398] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [11200.083791] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [11201.831406] Key type lgssc registered [11203.170575] Lustre: Echo OBD driver; http://www.lustre.org/ [11248.151087] Key type lgssc unregistered [11248.410469] LNet: 235322:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11248.423497] LNetError: 235322:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11248.465899] LNet: Removed LNI 192.168.202.18@tcp [11249.368578] Key type .llcrypt unregistered [11249.374663] Key type ._llcrypt unregistered [11278.806911] Key type ._llcrypt registered [11278.812706] Key type .llcrypt registered [11279.361539] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11279.372877] alg: No test for adler32 (adler32-zlib) [11280.525584] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [11280.810487] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [11282.535275] Key type lgssc registered [11283.764151] Lustre: Echo OBD driver; http://www.lustre.org/ [11369.426391] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11375.508590] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11400.505290] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11443.906080] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [11446.384859] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 03:38:17 (1787384297) [11485.682492] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11492.289217] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11511.050435] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11534.081551] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11536.018578] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [11540.973715] Lustre: lustre-OST0001-osc-ffff9f9c44627000: Connection to lustre-OST0001 (at 192.168.202.118@tcp) was lost; in progress operations using this service will wait for recovery to complete [11547.918580] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11558.331518] Lustre: Unmounted lustre-client [11595.001155] Key type lgssc unregistered [11595.400763] LNet: 242448:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11595.415248] LNetError: 242448:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11595.442218] LNet: Removed LNI 192.168.202.18@tcp [11596.635080] Key type .llcrypt unregistered [11596.640050] Key type ._llcrypt unregistered [11629.316233] Key type ._llcrypt registered [11629.331803] Key type .llcrypt registered [11631.301325] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 03:41:22 (1787384482) [11651.399155] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11651.431699] alg: No test for adler32 (adler32-zlib) [11652.922082] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [11653.358574] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [11655.207156] Key type lgssc registered [11657.296777] Lustre: Echo OBD driver; http://www.lustre.org/ [11706.961950] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11713.707452] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11737.717607] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 03:43:09 (1787384589) [11783.627775] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11790.374768] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11814.181026] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11815.911138] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [11823.502306] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [11835.191817] Lustre: Unmounted lustre-client [11946.291192] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11953.246703] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12010.190222] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12011.040955] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [12016.660510] Lustre: Unmounted lustre-client [12049.920501] Key type lgssc unregistered [12050.320506] LNet: 250157:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12050.328626] LNetError: 250157:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12050.347844] LNet: Removed LNI 192.168.202.18@tcp [12051.867131] Key type .llcrypt unregistered [12051.869554] Key type ._llcrypt unregistered [12087.813498] Key type ._llcrypt registered [12087.818985] Key type .llcrypt registered [12088.508794] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12088.534586] alg: No test for adler32 (adler32-zlib) [12089.889996] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [12090.279719] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [12092.031313] Key type lgssc registered [12093.659299] Lustre: Echo OBD driver; http://www.lustre.org/ [12182.399795] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12188.097539] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12210.251387] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12261.149946] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [12262.496668] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 03:51:54 (1787385114) [12297.121807] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12303.758604] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12325.392742] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12329.847786] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [12333.456178] Lustre: Unmounted lustre-client [12362.988529] Key type lgssc unregistered [12363.274340] LNet: 256807:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12363.285626] LNetError: 256807:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12363.303300] LNet: Removed LNI 192.168.202.18@tcp [12364.195304] Key type .llcrypt unregistered [12364.198791] Key type ._llcrypt unregistered [12384.426938] Key type ._llcrypt registered [12384.433600] Key type .llcrypt registered [12385.689927] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [12388.000289] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 03:53:59 (1787385239) [12397.493439] Key type .llcrypt unregistered [12397.495134] Key type ._llcrypt unregistered [12408.439753] Key type ._llcrypt registered [12408.469304] Key type .llcrypt registered [12409.295072] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12409.314606] alg: No test for adler32 (adler32-zlib) [12410.388514] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [12410.641392] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [12412.440301] Key type lgssc registered [12414.079195] Lustre: Echo OBD driver; http://www.lustre.org/ [12474.952611] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12491.059797] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [12493.351606] Lustre: Unmounted lustre-client [12525.150379] Key type lgssc unregistered [12525.454321] LNet: 260273:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12525.464543] LNetError: 260273:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12525.486743] LNet: Removed LNI 192.168.202.18@tcp [12526.267723] Key type .llcrypt unregistered [12526.272658] Key type ._llcrypt unregistered [12541.363805] Key type ._llcrypt registered [12541.367476] Key type .llcrypt registered [12542.811867] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 03:56:34 (1787385394) [12545.251720] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12545.341141] alg: No test for adler32 (adler32-zlib) [12546.489395] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [12546.800102] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [12548.543301] Key type lgssc registered [12549.927559] Lustre: Echo OBD driver; http://www.lustre.org/ [12635.719766] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12667.542938] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12673.328149] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12675.114974] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [12677.900225] Lustre: Unmounted lustre-client [12704.699273] Key type lgssc unregistered [12704.890681] LNet: 263822:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12704.895162] LNetError: 263822:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12704.905538] LNet: Removed LNI 192.168.202.18@tcp [12705.693902] Key type .llcrypt unregistered [12705.696934] Key type ._llcrypt unregistered [12726.677412] Key type ._llcrypt registered [12726.681082] Key type .llcrypt registered [12728.276397] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 03:59:40 (1787385580) [12731.503785] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12731.523098] alg: No test for adler32 (adler32-zlib) [12732.892281] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [12733.244054] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [12735.007214] Key type lgssc registered [12736.383416] Lustre: Echo OBD driver; http://www.lustre.org/ [12787.599967] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12793.781341] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12815.363414] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12818.071478] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [12823.529191] Lustre: lustre-MDT0000-mdc-ffff9f9c442c6000: Connection to lustre-MDT0000 (at 192.168.202.118@tcp) was lost; in progress operations using this service will wait for recovery to complete [12828.633958] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12828.662890] LustreError: 266996:0:(file.c:6170:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [12829.067490] LustreError: MGC192.168.202.118@tcp: Connection to MGS (at 192.168.202.118@tcp) was lost; in progress operations using this service will fail [12829.127824] Lustre: Unmounted lustre-client [12851.339564] Key type lgssc unregistered [12851.744093] LNet: 267414:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12851.750136] LNetError: 267414:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12852.777592] LNet: Removed LNI 192.168.202.18@tcp [12853.667173] Key type .llcrypt unregistered [12853.676268] Key type ._llcrypt unregistered [12872.758265] Key type ._llcrypt registered [12872.760162] Key type .llcrypt registered [12874.696724] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 04:02:06 (1787385726) [12877.689391] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12877.778647] alg: No test for adler32 (adler32-zlib) [12879.168701] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [12879.628505] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [12881.463353] Key type lgssc registered [12883.510110] Lustre: Echo OBD driver; http://www.lustre.org/ [12949.220868] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12955.980851] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12983.302813] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12984.901536] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [12989.940591] Lustre: lustre-MDT0001-mdc-ffff9f9c45716000: Connection to lustre-MDT0001 (at 192.168.202.118@tcp) was lost; in progress operations using this service will wait for recovery to complete [12993.163930] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [12996.808967] Lustre: Unmounted lustre-client [13020.719324] Key type lgssc unregistered [13021.247771] LNet: 271007:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13021.255540] LNetError: 271007:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13021.272341] LNet: Removed LNI 192.168.202.18@tcp [13021.890598] Key type .llcrypt unregistered [13021.892566] Key type ._llcrypt unregistered [13039.297030] Key type ._llcrypt registered [13039.315445] Key type .llcrypt registered [13041.100894] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 04:04:52 (1787385892) [13060.680064] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13060.815093] alg: No test for adler32 (adler32-zlib) [13062.041220] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [13062.427180] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [13064.114188] Key type lgssc registered [13066.027608] Lustre: Echo OBD driver; http://www.lustre.org/ [13200.351881] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13203.316859] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [13250.035554] Lustre: lustre-MDT0001-mdc-ffff9f9c44622000: Connection to lustre-MDT0001 (at 192.168.202.118@tcp) was lost; in progress operations using this service will wait for recovery to complete [13258.434717] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [13268.771380] Lustre: Unmounted lustre-client [13300.249609] Key type lgssc unregistered [13300.658125] LNet: 276807:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13300.671022] LNetError: 276807:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13300.705295] LNet: Removed LNI 192.168.202.18@tcp [13301.543527] Key type .llcrypt unregistered [13301.547151] Key type ._llcrypt unregistered [13321.807188] Key type ._llcrypt registered [13321.812173] Key type .llcrypt registered [13324.088384] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 04:09:35 (1787386175) [13326.865657] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [13330.726213] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 04:09:41 (1787386181) [13334.138001] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [13336.605242] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 04:09:48 (1787386188) [13338.663160] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [13341.404875] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 04:09:52 (1787386192) [13344.225850] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [13346.707577] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 04:09:58 (1787386198) [13348.759858] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [13351.130316] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 04:10:02 (1787386202) [13361.660271] Key type .llcrypt unregistered [13361.663627] Key type ._llcrypt unregistered [13372.415092] Key type ._llcrypt registered [13372.444989] Key type .llcrypt registered [13373.012597] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13373.026286] alg: No test for adler32 (adler32-zlib) [13374.190343] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [13374.424214] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [13376.159321] Key type lgssc registered [13377.718697] Lustre: Echo OBD driver; http://www.lustre.org/ [13464.975723] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13471.660692] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13497.350574] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13498.971896] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [13502.358089] Lustre: Unmounted lustre-client [13542.000707] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 04:13:13 (1787386393) [13580.710757] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13587.211670] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13622.810502] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13623.540394] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [13625.920193] Lustre: Unmounted lustre-client [13665.501768] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 04:15:17 (1787386517) [13676.575790] LNet: 1 local NIs in recovery (showing 1): 192.168.202.18@tcp [14317.782715] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14324.961396] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14365.017147] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14470.806799] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 04:28:43 (1787387323) [14485.408375] Key type lgssc unregistered [14485.803156] LNet: 290350:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14485.810847] LNetError: 290350:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14485.837865] LNet: Removed LNI 192.168.202.18@tcp [14486.587928] Key type .llcrypt unregistered [14486.591311] Key type ._llcrypt unregistered [14614.708854] Key type ._llcrypt registered [14614.715142] Key type .llcrypt registered [14615.741730] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14615.765859] alg: No test for adler32 (adler32-zlib) [14617.277334] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [14617.950307] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [14619.849494] Key type lgssc registered [14621.533428] Lustre: Echo OBD driver; http://www.lustre.org/ [14728.141224] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14735.244277] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14760.105589] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14793.526486] Key type lgssc unregistered [14793.836425] LNet: 294057:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14793.840305] LNetError: 294057:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14793.865652] LNet: Removed LNI 192.168.202.18@tcp [14794.620921] Key type .llcrypt unregistered [14794.622749] Key type ._llcrypt unregistered [14834.983218] Key type ._llcrypt registered [14834.986813] Key type .llcrypt registered [14836.087833] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 04:34:48 (1787387688) [14864.767793] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14864.862303] alg: No test for adler32 (adler32-zlib) [14866.151893] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [14866.429544] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [14868.106247] Key type lgssc registered [14869.627483] Lustre: Echo OBD driver; http://www.lustre.org/ [14925.591955] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 04:36:17 (1787387777) [14939.099976] Key type lgssc unregistered [14939.616308] LNet: 297618:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14939.622912] LNetError: 297618:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14939.643549] LNet: Removed LNI 192.168.202.18@tcp [14940.564186] Key type .llcrypt unregistered [14940.566601] Key type ._llcrypt unregistered [14953.092879] Key type ._llcrypt registered [14953.137356] Key type .llcrypt registered [14953.597753] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14953.626449] alg: No test for adler32 (adler32-zlib) [14954.696146] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [14954.933554] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [14956.632535] Key type lgssc registered [14958.091225] Lustre: Echo OBD driver; http://www.lustre.org/ [15013.750416] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15019.481487] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15042.337272] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15362.112388] LNet: Added LNI 192.168.202.18@tcp50 [8/256/0/180] [15364.807868] LNet: Removed LNI 192.168.202.18@tcp [15367.266675] Lustre: 302023:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff9f9c45717800: config has no valid NIDs, force dynamic NIDs [15368.573026] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [15368.818748] Lustre: Unmounted lustre-client [15371.979077] Key type lgssc unregistered [15372.432713] LNet: 302284:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15372.449124] LNetError: 302284:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15372.464713] LNet: Removed LNI 192.168.202.18@tcp50 [15373.307562] Key type .llcrypt unregistered [15373.310573] Key type ._llcrypt unregistered [15394.269603] Key type ._llcrypt registered [15394.270899] Key type .llcrypt registered [15395.001923] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15395.035032] alg: No test for adler32 (adler32-zlib) [15396.310627] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [15396.663625] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [15398.415198] Key type lgssc registered [15400.249752] Lustre: Echo OBD driver; http://www.lustre.org/ [15462.926786] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15469.327946] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15479.324110] LNet: Added LNI 192.168.202.18@tcp50 [8/256/0/180] [15480.717431] LNet: Removed LNI 192.168.202.18@tcp [15482.042833] Lustre: 304978:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff9f9c50a88000: config has no valid NIDs, force dynamic NIDs [15483.303659] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [15483.993434] Lustre: Unmounted lustre-client [15537.992913] Key type lgssc unregistered [15538.392836] LNet: 305853:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15538.403414] LNetError: 305853:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15538.427596] LNet: Removed LNI 192.168.202.18@tcp50 [15538.958690] Key type .llcrypt unregistered [15538.961108] Key type ._llcrypt unregistered [15553.506936] Key type ._llcrypt registered [15553.508414] Key type .llcrypt registered [15555.048071] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 04:46:46 (1787388406) [15578.212340] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15578.248780] alg: No test for adler32 (adler32-zlib) [15579.349864] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [15579.618020] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [15581.392073] Key type lgssc registered [15582.609764] Lustre: Echo OBD driver; http://www.lustre.org/ [15679.323972] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15687.398320] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15714.488705] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15762.861784] Key type lgssc unregistered [15763.165025] LNet: 310606:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15763.178052] LNetError: 310606:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15763.200234] LNet: Removed LNI 192.168.202.18@tcp [15764.118878] Key type .llcrypt unregistered [15764.122828] Key type ._llcrypt unregistered [15778.799767] Key type ._llcrypt registered [15778.802302] Key type .llcrypt registered [15781.337016] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 04:50:32 (1787388632) [15785.475593] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15785.591035] alg: No test for adler32 (adler32-zlib) [15787.020535] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [15787.606176] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [15789.559783] Key type lgssc registered [15792.167439] Lustre: Echo OBD driver; http://www.lustre.org/ [15862.979382] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15870.612949] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15897.946662] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15905.398496] Lustre: Mounted lustre-client - version 2.17.57_46_g5381a3b [15975.912849] Lustre: Mounted fs15246-client - version 2.17.57_46_g5381a3b [15991.621495] Lustre: Unmounted fs15246-client [16018.997319] Lustre: Unmounted lustre-client [16049.432531] Key type lgssc unregistered [16049.773256] LNet: 315478:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16049.781856] LNetError: 315478:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16049.842921] LNet: Removed LNI 192.168.202.18@tcp [16051.054418] Key type .llcrypt unregistered [16051.058291] Key type ._llcrypt unregistered [16067.341179] Key type ._llcrypt registered [16067.346719] Key type .llcrypt registered [16069.535222] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 04:55:20 (1787388920) [16071.836021] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16071.904925] alg: No test for adler32 (adler32-zlib) [16073.218895] Lustre: Lustre: Build Version: 2.17.57_46_g5381a3b [16073.672205] LNet: Added LNI 192.168.202.18@tcp [8/256/0/180] [16075.392711] Key type lgssc registered [16077.167161] Lustre: Echo OBD driver; http://www.lustre.org/ [16240.411845] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16248.644178] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16276.614380] Lustre: DEBUG MARKER: oleg218-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16400.263333] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 16025 sec ========= 05:00:51 (1787389251) [16402.171502] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 05:00:54 (1787389254) === [16406.537488] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 05:00:58 (1787389258) === [16445.561775] Key type lgssc unregistered [16445.861172] LNet: 324762:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16445.868570] LNetError: 324762:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16445.889490] LNet: Removed LNI 192.168.202.18@tcp [16446.823592] Key type .llcrypt unregistered [16446.826549] Key type ._llcrypt unregistered