[ 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 452278039 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: 2895288K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524584K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001010] APIC: Switch to symmetric I/O mode setup [ 0.002319] x2apic enabled [ 0.003006] Switched APIC routing to physical x2apic. [ 0.004011] kvm-guest: setup PV IPIs [ 0.006839] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.007000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.007023] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.008011] pid_max: default: 32768 minimum: 301 [ 0.010089] LSM: Security Framework initializing [ 0.011045] Yama: becoming mindful. [ 0.012055] SELinux: Initializing. [ 0.013055] *** VALIDATE selinux *** [ 0.021788] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.025814] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.026136] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028048] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029133] *** VALIDATE tmpfs *** [ 0.030445] *** VALIDATE proc *** [ 0.031215] *** VALIDATE cgroup *** [ 0.032016] *** VALIDATE cgroup2 *** [ 0.034056] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.035145] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036004] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.037026] Spectre V2 : User space: Vulnerable [ 0.038006] Speculative Store Bypass: Vulnerable [ 0.041403] debug: unmapping init [mem 0xffffffffade59000-0xffffffffade60fff] [ 0.044268] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.045875] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.046038] ... version: 2 [ 0.047023] ... bit width: 48 [ 0.048025] ... generic registers: 4 [ 0.049024] ... value mask: 0000ffffffffffff [ 0.050027] ... max period: 00007fffffffffff [ 0.051021] ... fixed-purpose events: 3 [ 0.052020] ... event mask: 000000070000000f [ 0.053481] rcu: Hierarchical SRCU implementation. [ 0.055694] smp: Bringing up secondary CPUs ... [ 0.056484] x86: Booting SMP configuration: [ 0.057021] .... node #0, CPUs: #1 #2 #3 [ 0.061021] smp: Brought up 1 node, 4 CPUs [ 0.063014] smpboot: Max logical packages: 1 [ 0.064018] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.140020] node 0 deferred pages initialised in 74ms [ 0.144009] devtmpfs: initialized [ 0.145268] x86/mm: Memory block size: 128MB [ 0.148873] gcov: version magic: 0x41383552 [ 0.152320] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.153067] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.156308] pinctrl core: initialized pinctrl subsystem [ 0.158201] [ 0.158830] ************************************************************* [ 0.161013] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.163009] ** ** [ 0.166012] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.168009] ** ** [ 0.169009] ** This means that this kernel is built to expose internal ** [ 0.171011] ** IOMMU data structures, which may compromise security on ** [ 0.173011] ** your system. ** [ 0.175011] ** ** [ 0.177013] ** If you see this message and you are not debugging the ** [ 0.179013] ** kernel, report this immediately to your vendor! ** [ 0.181010] ** ** [ 0.183010] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.185011] ************************************************************* [ 0.187667] NET: Registered protocol family 16 [ 0.190442] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.192053] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.195064] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.199134] cpuidle: using governor menu [ 0.200602] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.202605] PCI: Using configuration type 1 for base access [ 0.208144] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.219071] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.222101] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.226039] cryptd: max_cpu_qlen set to 1000 [ 0.229246] ACPI: Added _OSI(Module Device) [ 0.231051] ACPI: Added _OSI(Processor Device) [ 0.232013] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.234017] ACPI: Added _OSI(Processor Aggregator Device) [ 0.238840] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.243322] ACPI: Interpreter enabled [ 0.245066] ACPI: PM: (supports S0 S3 S4 S5) [ 0.246012] ACPI: Using IOAPIC for interrupt routing [ 0.248121] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.251408] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.261125] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.263041] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.266021] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.269074] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.273292] acpiphp: Slot [2] registered [ 0.275136] acpiphp: Slot [5] registered [ 0.277091] acpiphp: Slot [6] registered [ 0.279148] acpiphp: Slot [3] registered [ 0.281297] acpiphp: Slot [4] registered [ 0.282118] acpiphp: Slot [7] registered [ 0.284109] acpiphp: Slot [8] registered [ 0.285101] acpiphp: Slot [9] registered [ 0.287100] acpiphp: Slot [10] registered [ 0.288100] acpiphp: Slot [11] registered [ 0.290112] acpiphp: Slot [12] registered [ 0.291132] acpiphp: Slot [13] registered [ 0.293092] acpiphp: Slot [14] registered [ 0.294087] acpiphp: Slot [15] registered [ 0.296120] acpiphp: Slot [16] registered [ 0.297120] acpiphp: Slot [17] registered [ 0.299114] acpiphp: Slot [18] registered [ 0.300109] acpiphp: Slot [19] registered [ 0.302074] acpiphp: Slot [20] registered [ 0.303111] acpiphp: Slot [21] registered [ 0.305104] acpiphp: Slot [22] registered [ 0.306089] acpiphp: Slot [23] registered [ 0.307101] acpiphp: Slot [24] registered [ 0.310161] acpiphp: Slot [25] registered [ 0.312106] acpiphp: Slot [26] registered [ 0.314121] acpiphp: Slot [27] registered [ 0.315116] acpiphp: Slot [28] registered [ 0.317167] acpiphp: Slot [29] registered [ 0.318089] acpiphp: Slot [30] registered [ 0.319187] acpiphp: Slot [31] registered [ 0.321060] PCI host bridge to bus 0000:00 [ 0.322019] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.325030] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.327021] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.329024] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.332026] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.335031] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.337234] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.340163] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.343123] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.349518] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.354061] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.357017] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.359016] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.362020] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.364307] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.367888] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.371059] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.373923] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.378017] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.388758] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.393738] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.399850] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.406013] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.410021] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.443016] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.453751] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.467017] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.480017] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.522014] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.533000] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.535356] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.537367] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.540390] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.543248] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.547121] iommu: Default domain type: Passthrough [ 0.549407] SCSI subsystem initialized [ 0.551132] ACPI: bus type USB registered [ 0.552141] usbcore: registered new interface driver usbfs [ 0.554080] usbcore: registered new interface driver hub [ 0.556079] usbcore: registered new device driver usb [ 0.558173] pps_core: LinuxPPS API ver. 1 registered [ 0.559007] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.561055] PTP clock support registered [ 0.563143] EDAC MC: Ver: 3.0.0 [ 0.565351] PCI: Using ACPI for IRQ routing [ 0.568786] NetLabel: Initializing [ 0.570011] NetLabel: domain hash size = 128 [ 0.572009] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.574077] NetLabel: unlabeled traffic allowed by default [ 0.577086] vgaarb: loaded [ 0.579119] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.581014] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.587286] clocksource: Switched to clocksource kvm-clock [ 0.691258] VFS: Disk quotas dquot_6.6.0 [ 0.692971] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.695261] *** VALIDATE ramfs *** [ 0.696409] *** VALIDATE hugetlbfs *** [ 0.698448] pnp: PnP ACPI init [ 0.700712] pnp: PnP ACPI: found 6 devices [ 0.723173] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.726242] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.728233] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.730288] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.732582] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.734819] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.737385] NET: Registered protocol family 2 [ 0.739794] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.745056] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.749302] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.754600] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.758511] TCP: Hash tables configured (established 65536 bind 65536) [ 0.761980] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.765261] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.768204] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.771499] NET: Registered protocol family 1 [ 0.774241] RPC: Registered named UNIX socket transport module. [ 0.776329] RPC: Registered udp transport module. [ 0.777881] RPC: Registered tcp transport module. [ 0.779551] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.782091] NET: Registered protocol family 44 [ 0.784091] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.786645] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.789288] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.791663] PCI: CLS 0 bytes, default 64 [ 0.793271] Unpacking initramfs... [ 2.189369] debug: unmapping init [mem 0xffff9f35bcc64000-0xffff9f35bffcffff] [ 2.193489] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.195856] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.198746] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.705344] Initialise system trusted keyrings [ 2.706990] Key type blacklist registered [ 2.708950] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.718497] zbud: loaded [ 2.721972] *** VALIDATE nfs *** [ 2.723257] *** VALIDATE nfs4 *** [ 2.724948] pstore: using deflate compression [ 2.729213] Platform Keyring initialized [ 2.840651] NET: Registered protocol family 38 [ 2.842473] Key type asymmetric registered [ 2.844038] Asymmetric key parser 'x509' registered [ 2.845964] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.849109] io scheduler mq-deadline registered [ 2.851030] io scheduler kyber registered [ 2.852636] io scheduler bfq registered [ 2.854941] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.857918] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.861029] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.864120] ACPI: Power Button [PWRF] [ 2.869510] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.876052] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.888155] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.918309] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.948260] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.952934] Non-volatile memory driver v1.3 [ 2.954928] Linux agpgart interface v0.103 [ 2.988573] virtio_blk virtio1: [vda] 146864 512-byte logical blocks (75.2 MB/71.7 MiB) [ 2.991837] vda: detected capacity change from 0 to 75194368 [ 3.006896] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.009934] vdb: detected capacity change from 0 to 1073741824 [ 3.018180] libphy: Fixed MDIO Bus: probed [ 3.024249] usbcore: registered new interface driver usbserial_generic [ 3.026387] usbserial: USB Serial support registered for generic [ 3.028947] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.034107] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.036958] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.039763] mousedev: PS/2 mouse device common for all mice [ 3.042437] rtc_cmos 00:05: RTC can wake from S4 [ 3.045346] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.048626] rtc_cmos 00:05: registered as rtc0 [ 3.051638] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.054559] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.055865] intel_pstate: CPU model not supported [ 3.060850] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.066721] hid: raw HID events driver (C) Jiri Kosina [ 3.069074] usbcore: registered new interface driver usbhid [ 3.071312] usbhid: USB HID core driver [ 3.072700] drop_monitor: Initializing network drop monitor service [ 3.074954] Initializing XFRM netlink socket [ 3.077401] NET: Registered protocol family 10 [ 3.081369] Segment Routing with IPv6 [ 3.083077] NET: Registered protocol family 17 [ 3.085899] mpls_gso: MPLS GSO support [ 3.093069] RAS: Correctable Errors collector initialized. [ 3.095300] AVX version of gcm_enc/dec engaged. [ 3.096954] AES CTR mode by8 optimization enabled [ 3.184222] sched_clock: Marking stable (3184194762, 0)->(4017871146, -833676384) [ 3.188480] registered taskstats version 1 [ 3.190912] Loading compiled-in X.509 certificates [ 3.193413] zswap: loaded using pool lzo/zbud [ 3.226712] Key type big_key registered [ 3.242743] Key type encrypted registered [ 3.244722] ima: No TPM chip found, activating TPM-bypass! [ 3.246784] ima: Allocated hash algorithm: sha1 [ 3.248571] ima: No architecture policies found [ 3.250695] evm: Initialising EVM extended attributes: [ 3.252963] evm: security.selinux [ 3.254254] evm: security.ima [ 3.255715] evm: security.capability [ 3.257278] evm: HMAC attrs: 0x1 [ 3.259741] rtc_cmos 00:05: setting system clock to 2026-08-27 16:09:36 UTC (1787846976) [ 3.266907] debug: unmapping init [mem 0xffffffffaee03000-0xffffffffaeffffff] [ 3.271452] debug: unmapping init [mem 0xffffffffadb82000-0xffffffffade58fff] [ 3.285169] Write protecting the kernel read-only data: 28672k [ 3.289035] debug: unmapping init [mem 0xffffffffac203000-0xffffffffac3fffff] [ 3.292228] debug: unmapping init [mem 0xffffffffacb14000-0xffffffffacbfffff] [ 3.324454] 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.334854] systemd[1]: Detected virtualization kvm. [ 3.337817] systemd[1]: Detected architecture x86-64. [ 3.340666] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.363644] systemd[1]: No hostname configured. [ 3.364864] systemd[1]: Set hostname to . [ 3.366236] random: systemd: uninitialized urandom read (16 bytes read) [ 3.367784] systemd[1]: Initializing machine ID from random generator. [ 3.427355] random: ln: uninitialized urandom read (6 bytes read) [ 3.518585] random: systemd: uninitialized urandom read (16 bytes read) [ 3.521240] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 3.525847] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 3.529512] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ OK ] Reached target Slices. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. Starting Setup Virtual Console... [ OK ] Reached target Local File Systems. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Timers. [ OK ] Started Memstrack Anylazing Service. Starting Apply Kernel Variables... [ OK ] Reached target Initrd Root Device. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Sockets. Starting Journal Service... Starting Create Volatile Files and Directories... [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Journal Service. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.275980] device-mapper: uevent: version 1.0.3 [ 4.278199] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 5.104484] virtio_net virtio0 ens2: renamed from eth0 [ 5.163316] scsi host0: ata_piix [ 5.166796] scsi host1: ata_piix [ 5.168575] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.171286] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.986208] dracut-initqueue[580]: [ 8.988114] random: fast init done RTNETLINK answers: File exists [ 9.763200] random: crng init done [ 9.764484] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 10.327934] 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 Initrd Default Target. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Swap. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.604323] printk: systemd: 25 output lines suppressed due to ratelimiting [ 11.880463] SELinux: Disabled at runtime. [ 11.936956] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 11.946336] systemd[1]: Detected virtualization kvm. [ 11.948374] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.444679] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.448460] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.454550] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.459726] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.463823] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.472127] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.480694] systemd[1]: Starting Create list of required static device nodes for the current kernel... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Stopped target Switch Root. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Created slice system-sshd\x2dkeygen.slice. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Mounting Kernel Debug File System... [ OK ] Created slice User and Session Slice. [ OK ] Reached target rpc_pipefs.target. Starting Remount Root and Kernel File Systems... [ OK ] Created slice system-getty.slice. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Slices. [ OK ] Stopped target Initrd File Systems. [ OK ] Stopped target Initrd Root File System. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... Starting Apply Kernel Variables... Mounting POSIX Message Queue File System... [ OK ] Listening on Process Core Dump Socket. Mounting Huge Pages File System... Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ 12.688655] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Started Journal Service. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Kernel Debug File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Mounted Huge Pages File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 13.020300] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 13.288725] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.321358] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.446563] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.464110] EDAC sbridge: Ver: 1.1.2 [ 14.622883] Key type dns_resolver registered [ 14.937286] NFS: Registering the id_resolver key type [ 14.939488] Key type id_resolver registered [ 14.941276] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started dnf makecache --timer. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started D-Bus System Message Bus. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. Starting Network Manager... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Login Service... [ OK ] Started irqbalance daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting Network Manager Wait Online... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg446-client login: [ 58.042023] hrtimer: interrupt took 4014471 ns [ 75.176139] libcfs: loading out-of-tree module taints kernel. [ 75.369232] Key type ._llcrypt registered [ 75.371339] Key type .llcrypt registered [ 76.306332] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 76.329032] alg: No test for adler32 (adler32-zlib) [ 78.323065] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [ 79.610078] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 81.491461] Key type lgssc registered [ 84.192311] Lustre: Echo OBD driver; http://www.lustre.org/ [ 272.669876] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 277.981446] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 292.519446] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing check_logdir /tmp/testlogs/ [ 298.472800] Lustre: lustre-OST0000-osc-ffff9f36126f0000: disconnect after 23s idle [ 298.479173] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing yml_node [ 303.571535] Lustre: DEBUG MARKER: Client: 2.17.57.87 [ 306.654791] Lustre: DEBUG MARKER: MDS: 2.17.57.87 [ 310.167113] Lustre: DEBUG MARKER: OSS: 2.17.57.87 [ 312.283635] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Thu Aug 27 12:14:43 EDT 2026 [ 330.686731] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 332.138771] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 335.585937] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 346.000982] Lustre: Unmounted lustre-client [ 466.213326] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 474.191787] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 501.073192] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 542.036373] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 12:18:33 (1787847513) [ 545.249410] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 547.789347] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 12:18:38 (1787847518) [ 585.604825] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 592.521300] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 614.725446] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 615.502949] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 623.610865] Lustre: Unmounted lustre-client [ 651.064630] Key type lgssc unregistered [ 651.363362] LNet: 15020:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 651.382390] LNetError: 15020:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 651.402115] LNet: Removed LNI 192.168.204.46@tcp [ 652.180160] Key type .llcrypt unregistered [ 652.188884] Key type ._llcrypt unregistered [ 666.081645] Key type ._llcrypt registered [ 666.083722] Key type .llcrypt registered [ 667.444569] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 12:20:39 (1787847639) [ 668.941816] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 669.008768] alg: No test for adler32 (adler32-zlib) [ 670.144201] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [ 670.398156] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 672.112377] Key type lgssc registered [ 673.145741] Lustre: Echo OBD driver; http://www.lustre.org/ [ 750.540792] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 841.553937] Lustre: Unmounted lustre-client [ 871.088532] Key type lgssc unregistered [ 871.366313] LNet: 18858:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 871.380982] LNetError: 18858:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 871.410555] LNet: Removed LNI 192.168.204.46@tcp [ 872.137433] Key type .llcrypt unregistered [ 872.139675] Key type ._llcrypt unregistered [ 882.757313] Key type ._llcrypt registered [ 882.759489] Key type .llcrypt registered [ 884.177704] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 12:24:15 (1787847855) [ 886.765025] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 886.779155] alg: No test for adler32 (adler32-zlib) [ 888.047739] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [ 888.411261] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 890.176318] Key type lgssc registered [ 891.599756] Lustre: Echo OBD driver; http://www.lustre.org/ [ 945.102613] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 950.712106] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 972.440651] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 975.128669] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 989.287598] Lustre: Unmounted lustre-client [ 1027.771175] Key type lgssc unregistered [ 1028.049222] LNet: 22465:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1028.066433] LNetError: 22465:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1028.091455] LNet: Removed LNI 192.168.204.46@tcp [ 1028.737804] Key type .llcrypt unregistered [ 1028.743465] Key type ._llcrypt unregistered [ 1038.940611] Key type ._llcrypt registered [ 1038.943920] Key type .llcrypt registered [ 1040.257142] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 12:26:52 (1787848012) [ 1042.643743] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1042.730443] alg: No test for adler32 (adler32-zlib) [ 1043.861825] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [ 1044.044587] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 1045.728354] Key type lgssc registered [ 1046.958660] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1100.747439] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1107.104504] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1128.247889] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1130.990788] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 1140.629529] Lustre: Unmounted lustre-client [ 1170.719343] Key type lgssc unregistered [ 1171.117707] LNet: 25664:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1171.132170] LNetError: 25664:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1171.170107] LNet: Removed LNI 192.168.204.46@tcp [ 1171.933971] Key type .llcrypt unregistered [ 1171.941751] Key type ._llcrypt unregistered [ 1189.612842] Key type ._llcrypt registered [ 1189.617592] Key type .llcrypt registered [ 1191.175651] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 12:29:22 (1787848162) [ 1194.123499] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1194.174074] alg: No test for adler32 (adler32-zlib) [ 1195.316971] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [ 1195.602760] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 1197.304311] Key type lgssc registered [ 1198.754819] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1253.346552] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1259.712543] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1284.958647] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1287.951463] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 1293.305410] Lustre: lustre-MDT0000-mdc-ffff9f3605f08000: Connection to lustre-MDT0000 (at 192.168.204.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1298.433040] Lustre: lustre-MDT0001-mdc-ffff9f3605f08000: Connection to lustre-MDT0001 (at 192.168.204.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1302.271524] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1309.664147] Lustre: 26625:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787848266/real 1787848266] req@00000000f1e3bc01 x1874694680613760/t0(0) o400->MGC192.168.204.146@tcp@192.168.204.146@tcp:26/25 lens 224/224 e 0 to 1 dl 1787848282 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1309.691821] LustreError: MGC192.168.204.146@tcp: Connection to MGS (at 192.168.204.146@tcp) was lost; in progress operations using this service will fail [ 1312.607163] Lustre: Unmounted lustre-client [ 1333.929428] Key type lgssc unregistered [ 1334.281711] LNet: 29324:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1334.294701] LNetError: 29324:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1334.307137] LNet: Removed LNI 192.168.204.46@tcp [ 1335.049141] Key type .llcrypt unregistered [ 1335.053925] Key type ._llcrypt unregistered [ 1351.830638] Key type ._llcrypt registered [ 1351.833448] Key type .llcrypt registered [ 1353.037723] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 12:32:04 (1787848324) [ 1355.545433] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1355.567172] alg: No test for adler32 (adler32-zlib) [ 1356.642732] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [ 1356.844460] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 1358.504931] Key type lgssc registered [ 1359.800396] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1412.655799] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1420.592747] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1443.253629] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1445.992612] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 1450.984964] Lustre: lustre-OST0000-osc-ffff9f36029c0800: Connection to lustre-OST0000 (at 192.168.204.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1458.767623] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1469.123269] Lustre: Unmounted lustre-client [ 1497.284393] Key type lgssc unregistered [ 1497.559463] LNet: 32941:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1497.565792] LNetError: 32941:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1497.589770] LNet: Removed LNI 192.168.204.46@tcp [ 1498.423688] Key type .llcrypt unregistered [ 1498.432366] Key type ._llcrypt unregistered [ 1513.017073] Key type ._llcrypt registered [ 1513.020109] Key type .llcrypt registered [ 1514.430174] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 12:34:46 (1787848486) [ 1517.003088] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1517.029450] alg: No test for adler32 (adler32-zlib) [ 1518.227220] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [ 1518.455519] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 1520.192274] Key type lgssc registered [ 1521.348600] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1576.127828] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1582.086996] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1605.774218] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1608.688545] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 1612.574817] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1616.281299] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1628.425981] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-08-2712h36m39s [ 1630.477640] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1634.287866] Lustre: lustre-MDT0000-mdc-ffff9f36035ab000: Connection to lustre-MDT0000 (at 192.168.204.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1641.073233] LustreError: 36731:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff9f36035ab000: can't stat MDS #0: rc = -19 [ 1643.066934] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1652.716064] LustreError: MGC192.168.204.146@tcp: Connection to MGS (at 192.168.204.146@tcp) was lost; in progress operations using this service will fail [ 1652.731863] Lustre: Evicted from MGS (at 192.168.204.146@tcp) after server handle changed from 0x259864cea82eb47b to 0x259864cea82eb5e0 [ 1652.752436] Lustre: MGC192.168.204.146@tcp: Connection restored to 192.168.204.146@tcp (at 192.168.204.146@tcp) [ 1658.868061] LustreError: lustre-MDT0000-mdc-ffff9f36035ab000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1658.913559] Lustre: lustre-MDT0000-mdc-ffff9f36035ab000: Connection restored to 192.168.204.146@tcp (at 192.168.204.146@tcp) [ 1662.218419] Lustre: DEBUG MARKER: Wait for df (36731) ... [ 1663.773640] Lustre: DEBUG MARKER: done [ 1665.896958] Lustre: Unmounted lustre-client [ 1705.341944] Key type lgssc unregistered [ 1705.582925] LNet: 37752:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1705.591834] LNetError: 37752:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1705.607328] LNet: Removed LNI 192.168.204.46@tcp [ 1706.322214] Key type .llcrypt unregistered [ 1706.325405] Key type ._llcrypt unregistered [ 1739.607657] Key type ._llcrypt registered [ 1739.609939] Key type .llcrypt registered [ 1741.029482] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 12:38:32 (1787848712) [ 1743.998162] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1744.045509] alg: No test for adler32 (adler32-zlib) [ 1745.128789] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [ 1745.398622] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 1747.072204] Key type lgssc registered [ 1748.275794] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1795.765344] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1802.460179] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1828.913391] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1830.738874] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 1836.245093] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-08-2712h40m07s [ 1838.004983] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1846.781993] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1849.716726] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1870.304320] Lustre: 41893:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787848823/real 1787848823] req@000000008c90431e x1874695257332352/t0(0) o101->lustre-MDT0000-mdc-ffff9f3610647000@192.168.204.146@tcp:12/10 lens 576/1152 e 0 to 1 dl 1787848843 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1870.332756] Lustre: lustre-MDT0000-mdc-ffff9f3610647000: Connection to lustre-MDT0000 (at 192.168.204.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1870.368468] Lustre: lustre-MDT0000-mdc-ffff9f3610647000: Connection restored to 192.168.204.146@tcp (at 192.168.204.146@tcp) [ 1891.904922] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1895.640535] Lustre: DEBUG MARKER: done [ 1900.845110] Lustre: Unmounted lustre-client [ 1946.810462] Key type lgssc unregistered [ 1947.068779] LNet: 42787:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1947.090912] LNetError: 42787:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1947.123595] LNet: Removed LNI 192.168.204.46@tcp [ 1947.949324] Key type .llcrypt unregistered [ 1947.971664] Key type ._llcrypt unregistered [ 1978.804778] Key type ._llcrypt registered [ 1978.810745] Key type .llcrypt registered [ 1980.677407] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 12:42:31 (1787848951) [ 1982.427176] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1984.574887] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 12:42:36 (1787848956) [ 1989.408732] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1989.517964] alg: No test for adler32 (adler32-zlib) [ 1990.742374] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [ 1991.104494] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 1992.824220] Key type lgssc registered [ 1994.563527] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2022.644546] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 12:43:14 (1787848994) [ 2056.689791] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2063.080655] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2088.014032] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2090.988102] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 2094.793378] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 2099.072840] Lustre: Unmounted lustre-client [ 2115.963159] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 2165.116769] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2170.865845] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2171.428114] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 2175.764984] Lustre: Unmounted lustre-client [ 2238.298430] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2245.265784] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2245.934520] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 2251.971805] Lustre: Unmounted lustre-client [ 2276.386325] Lustre: DEBUG MARKER: files compared the same [ 2291.412714] Key type lgssc unregistered [ 2291.841570] LNet: 50408:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2291.863975] LNetError: 50408:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2291.896766] LNet: Removed LNI 192.168.204.46@tcp [ 2292.839901] Key type .llcrypt unregistered [ 2292.843451] Key type ._llcrypt unregistered [ 2310.405622] Key type ._llcrypt registered [ 2310.408324] Key type .llcrypt registered [ 2312.236453] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 12:48:03 (1787849283) [ 2315.117132] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2315.160667] alg: No test for adler32 (adler32-zlib) [ 2316.366986] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [ 2316.638299] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 2318.384227] Key type lgssc registered [ 2320.006435] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2377.474531] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2383.937558] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2409.300610] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2412.177325] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 2416.238083] Lustre: Unmounted lustre-client [ 2456.997905] Key type lgssc unregistered [ 2457.412351] LNet: 53996:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2457.440553] LNetError: 53996:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2457.470369] LNet: Removed LNI 192.168.204.46@tcp [ 2458.741117] Key type .llcrypt unregistered [ 2458.744531] Key type ._llcrypt unregistered [ 2479.482855] Key type ._llcrypt registered [ 2479.490506] Key type .llcrypt registered [ 2481.373947] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 12:50:52 (1787849452) [ 2484.263073] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2484.307270] alg: No test for adler32 (adler32-zlib) [ 2485.699615] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [ 2486.128934] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 2487.825801] Key type lgssc registered [ 2489.746400] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2579.111602] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2612.939180] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2618.870511] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2648.023479] Key type lgssc unregistered [ 2648.271165] LNet: 57485:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2648.278286] LNetError: 57485:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2648.305257] LNet: Removed LNI 192.168.204.46@tcp [ 2649.000817] Key type .llcrypt unregistered [ 2649.006044] Key type ._llcrypt unregistered [ 2665.601730] Key type ._llcrypt registered [ 2665.603668] Key type .llcrypt registered [ 2666.941289] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 12:53:58 (1787849638) [ 2670.633788] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2670.733828] alg: No test for adler32 (adler32-zlib) [ 2671.932308] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [ 2672.205130] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 2673.912188] Key type lgssc registered [ 2675.750694] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2757.332748] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 2765.670555] Lustre: Unmounted lustre-client [ 2810.173733] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 12:56:21 (1787849781) [ 2948.965582] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2971.185142] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 2978.507628] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2978.520990] LustreError: 63961:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff9f3606940800: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 2978.544176] Lustre: 58468:0:(llite_lib.c:4352:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.204.146@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 2988.947114] Lustre: Unmounted lustre-client [ 3043.164518] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 13:00:14 (1787850014) [ 3055.016860] Key type lgssc unregistered [ 3055.245387] LNet: 65178:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3055.254633] LNetError: 65178:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3055.279704] LNet: Removed LNI 192.168.204.46@tcp [ 3055.907617] Key type .llcrypt unregistered [ 3055.910075] Key type ._llcrypt unregistered [ 3069.630244] Key type ._llcrypt registered [ 3069.635648] Key type .llcrypt registered [ 3070.085420] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3070.102853] alg: No test for adler32 (adler32-zlib) [ 3071.222126] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [ 3071.457694] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 3073.136202] Key type lgssc registered [ 3074.417476] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3125.958887] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3131.973033] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3155.858385] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3158.677376] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 3165.946030] Lustre: Unmounted lustre-client [ 3206.234936] Key type lgssc unregistered [ 3206.584282] LNet: 68211:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3206.594564] LNetError: 68211:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3206.658860] LNet: Removed LNI 192.168.204.46@tcp [ 3207.493920] Key type .llcrypt unregistered [ 3207.508768] Key type ._llcrypt unregistered [ 3220.138421] Key type ._llcrypt registered [ 3220.141731] Key type .llcrypt registered [ 3220.551374] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3220.590196] alg: No test for adler32 (adler32-zlib) [ 3221.956636] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [ 3222.418488] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 3224.305054] Key type lgssc registered [ 3226.659460] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3351.795210] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3359.226018] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3384.598867] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3395.720917] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 3399.587162] Lustre: Unmounted lustre-client [ 3430.309816] Key type lgssc unregistered [ 3430.595348] LNet: 71650:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3430.606448] LNetError: 71650:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3430.639834] LNet: Removed LNI 192.168.204.46@tcp [ 3431.461664] Key type .llcrypt unregistered [ 3431.464186] Key type ._llcrypt unregistered [ 3447.680721] Key type ._llcrypt registered [ 3447.682923] Key type .llcrypt registered [ 3449.172948] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 13:07:00 (1787850420) [ 3452.278539] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3452.310361] alg: No test for adler32 (adler32-zlib) [ 3453.598441] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [ 3453.988948] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 3455.680245] Key type lgssc registered [ 3456.725379] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3514.162389] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3521.610907] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3544.011177] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3546.725892] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 3553.550033] Lustre: Unmounted lustre-client [ 3554.210754] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 3558.904923] Lustre: Unmounted lustre-client [ 3588.440500] Key type lgssc unregistered [ 3588.780617] LNet: 75335:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3588.785472] LNetError: 75335:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3588.819241] LNet: Removed LNI 192.168.204.46@tcp [ 3589.589731] Key type .llcrypt unregistered [ 3589.591895] Key type ._llcrypt unregistered [ 3602.490108] Key type ._llcrypt registered [ 3602.498484] Key type .llcrypt registered [ 3603.134636] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3603.152422] alg: No test for adler32 (adler32-zlib) [ 3604.386969] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [ 3604.595302] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 3606.272227] Key type lgssc registered [ 3607.519464] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3659.543970] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3666.598350] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3689.588927] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3691.377457] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 3700.391098] Lustre: Unmounted lustre-client [ 3731.621562] Key type lgssc unregistered [ 3731.950033] LNet: 78390:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3731.957467] LNetError: 78390:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3731.981755] LNet: Removed LNI 192.168.204.46@tcp [ 3732.859099] Key type .llcrypt unregistered [ 3732.867582] Key type ._llcrypt unregistered [ 3753.535686] Key type ._llcrypt registered [ 3753.540621] Key type .llcrypt registered [ 3755.097634] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 13:12:06 (1787850726) [ 3758.062710] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3758.196509] alg: No test for adler32 (adler32-zlib) [ 3759.429469] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [ 3759.732874] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 3761.576579] Key type lgssc registered [ 3763.170753] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3824.365289] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3831.442569] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3858.869278] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3860.849720] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 3872.453409] Lustre: lustre: root_squash is set to 0:0 [ 3879.358976] Lustre: lustre: nosquash_nids is cleared [ 3889.729737] Lustre: lustre: root_squash is set to 500:500 [ 3899.077608] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.204.46@tcp 192.168.0.[2,10]@tcp [ 3907.706335] Lustre: lustre: nosquash_nids set to 192.168.204.46/24@tcp [ 3913.513358] Lustre: Unmounted lustre-client [ 3947.857141] Key type lgssc unregistered [ 3948.159252] LNet: 82350:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3948.169510] LNetError: 82350:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3948.186498] LNet: Removed LNI 192.168.204.46@tcp [ 3948.951144] Key type .llcrypt unregistered [ 3948.954535] Key type ._llcrypt unregistered [ 3967.520657] Key type ._llcrypt registered [ 3967.523128] Key type .llcrypt registered [ 3969.074796] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 13:15:40 (1787850940) [ 3971.789817] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3973.745971] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 13:15:45 (1787850945) [ 3976.569105] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3976.601341] alg: No test for adler32 (adler32-zlib) [ 3977.710846] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [ 3977.964768] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 3979.704242] Key type lgssc registered [ 3980.924271] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4032.881580] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4040.796095] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4067.201411] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4068.034669] Lustre: lustre: root_squash is set to 500:500 [ 4068.083679] Lustre: lustre: nosquash_nids set to 192.168.204.46/24@tcp [ 4069.243037] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 4075.979604] Lustre: Unmounted lustre-client [ 4105.259727] Key type lgssc unregistered [ 4105.554715] LNet: 86312:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4105.575812] LNetError: 86312:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4106.599224] LNet: Removed LNI 192.168.204.46@tcp [ 4107.306324] Key type .llcrypt unregistered [ 4107.311600] Key type ._llcrypt unregistered [ 4121.428955] Key type ._llcrypt registered [ 4121.432265] Key type .llcrypt registered [ 4122.932408] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 4125.398887] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 13:18:16 (1787851096) [ 4148.952109] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4149.034607] alg: No test for adler32 (adler32-zlib) [ 4150.291360] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [ 4150.640513] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 4152.436446] Key type lgssc registered [ 4154.088311] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4253.436419] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4260.683346] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4289.362514] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4377.075989] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4385.389431] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4414.733641] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4430.512260] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 4471.129311] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9f36029c5000.ost_server_uuid 50 [ 4472.979558] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9f36029c5000.ost_server_uuid in IDLE state after 0 sec [ 4473.601341] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 4479.306512] Lustre: Unmounted lustre-client [ 4481.959064] Lustre: Unmounted lustre-client [ 4527.574660] Key type lgssc unregistered [ 4527.845716] LNet: 93989:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4527.854647] LNetError: 93989:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4527.894328] LNet: Removed LNI 192.168.204.46@tcp [ 4528.733525] Key type .llcrypt unregistered [ 4528.736481] Key type ._llcrypt unregistered [ 4562.222432] Key type ._llcrypt registered [ 4562.225564] Key type .llcrypt registered [ 4563.700324] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 13:25:35 (1787851535) [ 4584.035862] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4584.067342] alg: No test for adler32 (adler32-zlib) [ 4585.184653] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [ 4585.449140] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 4587.176988] Key type lgssc registered [ 4588.962572] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4686.654702] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4693.640785] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4724.477665] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4727.010365] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 4748.770930] Lustre: 95354:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787851705/real 1787851705] req@00000000221c0596 x1874698235286784/t0(0) o400->lustre-OST0000-osc-ffff9f3602a36000@192.168.204.146@tcp:28/4 lens 224/224 e 0 to 1 dl 1787851721 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4748.816351] Lustre: lustre-OST0000-osc-ffff9f3602a36000: Connection to lustre-OST0000 (at 192.168.204.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4752.929270] Lustre: 95355:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787851710/real 1787851710] req@000000005c0c1592 x1874698235287296/t0(0) o400->lustre-OST0000-osc-ffff9f3602a36000@192.168.204.146@tcp:28/4 lens 224/224 e 0 to 1 dl 1787851726 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4757.984451] Lustre: 95354:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787851715/real 1787851715] req@00000000165556c1 x1874698235287808/t0(0) o400->lustre-OST0000-osc-ffff9f3602a36000@192.168.204.146@tcp:28/4 lens 224/224 e 0 to 1 dl 1787851731 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4761.194162] Lustre: lustre-OST0000-osc-ffff9f3602a36000: Connection restored to 192.168.204.146@tcp (at 192.168.204.146@tcp) [ 4763.104218] Lustre: 95354:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787851720/real 1787851720] req@00000000107a46a8 x1874698235288320/t0(0) o400->lustre-OST0000-osc-ffff9f3602a36000@192.168.204.146@tcp:28/4 lens 224/224 e 0 to 1 dl 1787851736 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4764.137811] Lustre: lustre-MDT0000-mdc-ffff9f3602a36000: Connection to lustre-MDT0000 (at 192.168.204.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4780.512272] Lustre: 95354:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787851737/real 1787851737] req@00000000144b0446 x1874698235290624/t0(0) o400->MGC192.168.204.146@tcp@192.168.204.146@tcp:26/25 lens 224/224 e 0 to 1 dl 1787851753 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4780.580396] LustreError: MGC192.168.204.146@tcp: Connection to MGS (at 192.168.204.146@tcp) was lost; in progress operations using this service will fail [ 4790.786424] Lustre: Evicted from MGS (at 192.168.204.146@tcp) after server handle changed from 0x83f84ba85e7c4b1c to 0x83f84ba85e7c4cb2 [ 4790.806482] Lustre: MGC192.168.204.146@tcp: Connection restored to 192.168.204.146@tcp (at 192.168.204.146@tcp) [ 4798.891985] Lustre: lustre-MDT0000-mdc-ffff9f3602a36000: Connection restored to 192.168.204.146@tcp (at 192.168.204.146@tcp) [ 4804.018363] Lustre: Unmounted lustre-client [ 4834.847929] Key type lgssc unregistered [ 4835.155060] LNet: 99663:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4835.171648] LNetError: 99663:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4835.200448] LNet: Removed LNI 192.168.204.46@tcp [ 4835.938408] Key type .llcrypt unregistered [ 4835.942171] Key type ._llcrypt unregistered [ 4853.429254] Key type ._llcrypt registered [ 4853.432327] Key type .llcrypt registered [ 4855.327798] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 13:30:26 (1787851826) [ 4871.169682] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4871.199256] alg: No test for adler32 (adler32-zlib) [ 4872.350342] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [ 4872.594959] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 4874.264324] Key type lgssc registered [ 4875.828779] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4923.277915] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5050.885560] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5057.562846] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5085.217448] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5088.049105] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 5090.464199] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 5092.677367] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 13:34:24 (1787852064) [ 5406.633841] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5416.986991] Lustre: Unmounted lustre-client [ 5575.131319] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5582.202206] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5612.212819] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5666.433458] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 13:43:57 (1787852637) [ 5792.603270] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5801.709590] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5837.235451] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5838.241467] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 5843.354891] Lustre: Unmounted lustre-client [ 5883.906906] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 13:47:35 (1787852855) [ 6008.051185] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6014.689296] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6045.184912] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6045.836809] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 6051.324920] Lustre: Unmounted lustre-client [ 6082.374123] Key type lgssc unregistered [ 6082.749326] LNet: 122738:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6082.763774] LNetError: 122738:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6082.783673] LNet: Removed LNI 192.168.204.46@tcp [ 6083.595162] Key type .llcrypt unregistered [ 6083.599738] Key type ._llcrypt unregistered [ 6101.757850] Key type ._llcrypt registered [ 6101.762391] Key type .llcrypt registered [ 6103.796791] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 13:51:14 (1787853074) [ 6107.434994] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6107.624761] alg: No test for adler32 (adler32-zlib) [ 6108.905492] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [ 6109.394071] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 6111.248240] Key type lgssc registered [ 6113.325551] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6177.007730] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6185.426275] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6211.596057] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6218.301579] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 6232.103212] Lustre: Unmounted lustre-client [ 6263.460468] Key type lgssc unregistered [ 6263.871468] LNet: 126335:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6263.884580] LNetError: 126335:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6263.913268] LNet: Removed LNI 192.168.204.46@tcp [ 6264.753377] Key type .llcrypt unregistered [ 6264.756421] Key type ._llcrypt unregistered [ 6285.372967] Key type ._llcrypt registered [ 6285.377392] Key type .llcrypt registered [ 6287.797580] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 13:54:18 (1787853258) [ 6291.065148] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6291.110483] alg: No test for adler32 (adler32-zlib) [ 6292.343761] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [ 6292.683294] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 6294.473762] Key type lgssc registered [ 6296.471433] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6362.135547] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6369.714371] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6395.896128] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6403.140840] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 6408.194865] Lustre: lustre-OST0000-osc-ffff9f3606255000: Connection to lustre-OST0000 (at 192.168.204.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6422.463569] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff9f3606255000.ost_server_uuid 50 [ 6424.358325] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 6436.312738] Lustre: Unmounted lustre-client [ 6460.956846] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 13:57:12 (1787853432) [ 6498.478737] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6505.328396] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6532.580441] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6561.346936] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6561.933754] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 6567.408417] Lustre: lustre-OST0000-osc-ffff9f361899c800: Connection to lustre-OST0000 (at 192.168.204.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6603.806605] Lustre: Unmounted lustre-client [ 6657.227272] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 14:00:28 (1787853628) [ 6698.808792] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6706.734956] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6733.774445] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6759.602396] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6760.825949] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 6766.067049] Lustre: lustre-OST0000-osc-ffff9f360381b000: Connection to lustre-OST0000 (at 192.168.204.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6783.354917] Lustre: Unmounted lustre-client [ 6836.418992] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 14:03:27 (1787853807) [ 6946.504959] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6953.560115] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6978.179258] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7047.453538] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7053.758293] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7074.329330] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7104.672115] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 7148.654594] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7151.451141] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 1 sec [ 7153.529553] Lustre: Unmounted lustre-client [ 7184.890844] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 14:09:16 (1787854156) [ 7219.809515] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7226.253434] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7248.882685] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7283.351085] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7322.571188] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 7365.522864] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7367.375975] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7379.877324] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7390.251028] Lustre: Unmounted lustre-client [ 7455.071358] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 14:13:46 (1787854426) [ 7490.661456] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7497.320939] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7519.797103] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7520.468900] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 7543.329137] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7545.329545] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7564.678859] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9f3602a30000.ost_server_uuid 50 [ 7566.422973] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9f3602a30000.ost_server_uuid in IDLE state after 0 sec [ 7569.664407] Lustre: Unmounted lustre-client [ 7569.968317] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 7569.977700] Lustre: Skipped 3 previous similar messages [ 7570.129690] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 7571.414566] LustreError: 149778:0:(sec.c:403:import_sec_validate_get()) import 00000000540e56c2 (NEW) with no sec [ 7635.516674] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 14:16:47 (1787854607) [ 7672.587455] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7678.588807] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7700.346939] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7721.328324] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7721.867456] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 7722.087184] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 7733.518161] Lustre: Unmounted lustre-client [ 7733.521815] Lustre: Skipped 1 previous similar message [ 7775.164550] Key type lgssc unregistered [ 7775.465903] LNet: 153182:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7775.474782] LNetError: 153182:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7775.507723] LNet: Removed LNI 192.168.204.46@tcp [ 7776.278251] Key type .llcrypt unregistered [ 7776.281239] Key type ._llcrypt unregistered [ 7793.632757] Key type ._llcrypt registered [ 7793.637052] Key type .llcrypt registered [ 7795.171166] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 14:19:26 (1787854766) [ 7796.181754] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7796.208588] alg: No test for adler32 (adler32-zlib) [ 7797.247507] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [ 7797.517119] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 7799.208291] Key type lgssc registered [ 7800.659873] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7853.903454] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7859.682129] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7884.403269] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7905.832969] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7907.386802] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7908.550712] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 7911.540307] LustreError: lustre-MDT0001-mdc-ffff9f361899d000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 7911.577382] Lustre: lustre-MDT0001-mdc-ffff9f361899d000: Connection restored to 192.168.204.146@tcp (at 192.168.204.146@tcp) [ 7919.201585] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7921.075487] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7944.725389] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7949.303246] Lustre: Unmounted lustre-client [ 7975.171755] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 14:22:26 (1787854946) [ 8076.913501] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8083.359945] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8107.826556] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8108.871454] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 8137.827693] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8139.597763] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 8152.350036] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 8162.663605] Lustre: Unmounted lustre-client [ 8206.728559] Key type lgssc unregistered [ 8207.039990] LNet: 162786:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8207.047073] LNetError: 162786:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8207.060485] LNet: Removed LNI 192.168.204.46@tcp [ 8207.754530] Key type .llcrypt unregistered [ 8207.758301] Key type ._llcrypt unregistered [ 8239.838475] Key type ._llcrypt registered [ 8239.843518] Key type .llcrypt registered [ 8241.437708] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 14:26:52 (1787855212) [ 8244.515187] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8244.533940] alg: No test for adler32 (adler32-zlib) [ 8245.790821] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [ 8246.179297] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 8247.923162] Key type lgssc registered [ 8249.850897] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8303.922178] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8309.602542] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8329.381955] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8332.037128] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 8377.661638] Lustre: Unmounted lustre-client [ 8422.760608] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8423.452955] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 8460.643077] Lustre: Unmounted lustre-client [ 8498.138877] Key type lgssc unregistered [ 8498.456394] LNet: 167239:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8498.463913] LNetError: 167239:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8498.487488] LNet: Removed LNI 192.168.204.46@tcp [ 8499.427165] Key type .llcrypt unregistered [ 8499.431169] Key type ._llcrypt unregistered [ 8516.360138] Key type ._llcrypt registered [ 8516.362288] Key type .llcrypt registered [ 8517.509554] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 14:31:29 (1787855489) [ 8519.891201] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8519.912260] alg: No test for adler32 (adler32-zlib) [ 8520.913473] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [ 8521.136682] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 8522.824418] Key type lgssc registered [ 8524.666771] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8585.484974] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8594.928095] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8620.496278] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8627.785492] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 8651.465051] Lustre: Unmounted lustre-client [ 8684.255764] Key type lgssc unregistered [ 8684.496828] LNet: 170999:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8684.517290] LNetError: 170999:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8684.562384] LNet: Removed LNI 192.168.204.46@tcp [ 8685.435967] Key type .llcrypt unregistered [ 8685.439796] Key type ._llcrypt unregistered [ 8699.346136] Key type ._llcrypt registered [ 8699.350106] Key type .llcrypt registered [ 8699.639754] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8699.670237] alg: No test for adler32 (adler32-zlib) [ 8700.677472] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [ 8700.847463] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 8702.496276] Key type lgssc registered [ 8703.524232] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8762.542995] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8769.462935] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8795.435526] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8801.927859] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 8811.241996] Lustre: Unmounted lustre-client [ 8848.824620] Key type lgssc unregistered [ 8849.206732] LNet: 174069:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8849.228554] LNetError: 174069:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8849.256598] LNet: Removed LNI 192.168.204.46@tcp [ 8850.278987] Key type .llcrypt unregistered [ 8850.283748] Key type ._llcrypt unregistered [ 8866.859898] Key type ._llcrypt registered [ 8866.863468] Key type .llcrypt registered [ 8868.034608] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 14:37:19 (1787855839) [ 8870.454792] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8870.553597] alg: No test for adler32 (adler32-zlib) [ 8871.664332] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [ 8871.880062] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 8873.520200] Key type lgssc registered [ 8874.805970] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8932.380909] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8940.064925] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8973.040424] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8975.832330] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 8998.438457] Lustre: Unmounted lustre-client [ 9028.618583] Key type lgssc unregistered [ 9028.911611] LNet: 177827:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9028.923330] LNetError: 177827:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9028.945612] LNet: Removed LNI 192.168.204.46@tcp [ 9029.808247] Key type .llcrypt unregistered [ 9029.811041] Key type ._llcrypt unregistered [ 9044.129418] Key type ._llcrypt registered [ 9044.146738] Key type .llcrypt registered [ 9044.651445] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9044.675416] alg: No test for adler32 (adler32-zlib) [ 9045.872294] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [ 9046.138272] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 9047.864670] Key type lgssc registered [ 9048.848890] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9107.492979] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9114.783507] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9139.342367] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9141.192963] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 9149.140823] Lustre: Unmounted lustre-client [ 9178.809382] Key type lgssc unregistered [ 9179.184282] LNet: 180895:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9179.202585] LNetError: 180895:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9179.255520] LNet: Removed LNI 192.168.204.46@tcp [ 9180.179655] Key type .llcrypt unregistered [ 9180.182267] Key type ._llcrypt unregistered [ 9200.710407] Key type ._llcrypt registered [ 9200.712101] Key type .llcrypt registered [ 9202.179982] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 14:42:53 (1787856173) [ 9228.108835] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9228.226340] alg: No test for adler32 (adler32-zlib) [ 9229.332495] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [ 9229.587474] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 9231.248255] Key type lgssc registered [ 9232.608356] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9327.850385] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9335.116845] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9361.823642] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9407.141853] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 14:46:18 (1787856378) [ 9451.924624] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9457.607211] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9480.302191] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9487.444683] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 9493.032335] Lustre: Unmounted lustre-client [ 9520.938703] Key type lgssc unregistered [ 9521.407913] LNet: 188004:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9521.421872] LNetError: 188004:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9521.454491] LNet: Removed LNI 192.168.204.46@tcp [ 9522.256471] Key type .llcrypt unregistered [ 9522.259646] Key type ._llcrypt unregistered [ 9537.694758] Key type ._llcrypt registered [ 9537.697711] Key type .llcrypt registered [ 9539.486860] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 9541.813966] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 14:48:33 (1787856513) [ 9562.418263] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9562.497468] alg: No test for adler32 (adler32-zlib) [ 9563.670973] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [ 9564.010227] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [ 9565.768292] Key type lgssc registered [ 9567.775922] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9661.141444] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9666.890842] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9695.569979] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9717.676475] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9719.381171] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [ 9745.944508] Lustre: Unmounted lustre-client [ 9890.479959] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9898.012402] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9923.024459] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9967.110848] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 14:55:38 (1787856938) [ 9969.044844] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 9971.267365] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 14:55:42 (1787856942) [10074.907255] Key type lgssc unregistered [10075.203059] LNet: 199454:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10075.219437] LNetError: 199454:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10075.240314] LNet: Removed LNI 192.168.204.46@tcp [10076.232626] Key type .llcrypt unregistered [10076.235457] Key type ._llcrypt unregistered [10094.741160] Key type ._llcrypt registered [10094.743657] Key type .llcrypt registered [10096.425243] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 14:57:47 (1787857067) [10140.697366] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10140.817494] alg: No test for adler32 (adler32-zlib) [10142.013707] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [10142.292101] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [10144.008576] Key type lgssc registered [10145.281490] Lustre: Echo OBD driver; http://www.lustre.org/ [10195.668068] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10202.420600] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10238.228099] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10267.635288] Key type lgssc unregistered [10267.913156] LNet: 203193:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10267.927694] LNetError: 203193:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10267.958082] LNet: Removed LNI 192.168.204.46@tcp [10268.720874] Key type .llcrypt unregistered [10268.722519] Key type ._llcrypt unregistered [10285.277876] Key type ._llcrypt registered [10285.280701] Key type .llcrypt registered [10286.468435] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 15:00:58 (1787857258) [10289.658286] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10289.714392] alg: No test for adler32 (adler32-zlib) [10290.778177] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [10291.072193] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [10292.752626] Key type lgssc registered [10293.871088] Lustre: Echo OBD driver; http://www.lustre.org/ [10346.094751] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10353.016151] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10378.740276] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10380.568193] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [10391.030432] Lustre: lustre-MDT0000-mdc-ffff9f3612707800: Connection to lustre-MDT0000 (at 192.168.204.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [10396.146445] Lustre: lustre-MDT0001-mdc-ffff9f3612707800: Connection to lustre-MDT0001 (at 192.168.204.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [10407.392195] Lustre: 204197:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787857364/real 1787857364] req@00000000144b0446 x1874704218537216/t0(0) o400->MGC192.168.204.146@tcp@192.168.204.146@tcp:26/25 lens 224/224 e 0 to 1 dl 1787857380 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10407.410001] LustreError: MGC192.168.204.146@tcp: Connection to MGS (at 192.168.204.146@tcp) was lost; in progress operations using this service will fail [10433.514592] Lustre: Evicted from MGS (at 192.168.204.146@tcp) after server handle changed from 0x4d47cb17c49f33c4 to 0x4d47cb17c49f4fee [10433.541974] Lustre: MGC192.168.204.146@tcp: Connection restored to 192.168.204.146@tcp (at 192.168.204.146@tcp) [10441.220127] LustreError: lustre-MDT0000-mdc-ffff9f3612707800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [10441.264707] Lustre: lustre-MDT0000-mdc-ffff9f3612707800: Connection restored to 192.168.204.146@tcp (at 192.168.204.146@tcp) [10445.731245] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10447.480147] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10454.263767] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10455.896662] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [10459.352531] Lustre: Unmounted lustre-client [10486.119827] Key type lgssc unregistered [10486.425240] LNet: 208150:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10486.433574] LNetError: 208150:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10486.463475] LNet: Removed LNI 192.168.204.46@tcp [10487.343780] Key type .llcrypt unregistered [10487.345901] Key type ._llcrypt unregistered [10505.584269] Key type ._llcrypt registered [10505.587359] Key type .llcrypt registered [10507.297774] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 15:04:38 (1787857478) [10510.402846] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10510.428690] alg: No test for adler32 (adler32-zlib) [10511.584160] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [10511.961573] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [10513.746983] Key type lgssc registered [10515.342266] Lustre: Echo OBD driver; http://www.lustre.org/ [10571.316421] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10578.234255] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10645.512177] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10651.631584] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10687.953391] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10712.118294] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10753.323403] Key type lgssc unregistered [10753.699636] LNet: 213450:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10753.707040] LNetError: 213450:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10753.728942] LNet: Removed LNI 192.168.204.46@tcp [10754.639509] Key type .llcrypt unregistered [10754.643198] Key type ._llcrypt unregistered [10803.140414] Key type ._llcrypt registered [10803.144075] Key type .llcrypt registered [10804.683815] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 15:09:36 (1787857776) [10846.144872] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10846.166821] alg: No test for adler32 (adler32-zlib) [10847.281790] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [10847.588677] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [10849.306858] Key type lgssc registered [10850.587324] Lustre: Echo OBD driver; http://www.lustre.org/ [10953.853994] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10960.908988] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10989.206431] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11034.842951] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 15:13:26 (1787858006) [11044.113905] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 15:13:35 (1787858015) [11089.047221] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11095.316985] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11122.248645] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11128.413526] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [11134.298986] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [11136.902353] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [11139.062887] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [11140.933483] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [11148.796447] Lustre: lustre-MDT0000-mdc-ffff9f3602a37000: Connection to lustre-MDT0000 (at 192.168.204.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [11165.152175] Lustre: 215348:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787858122/real 1787858122] req@00000000ec1d168a x1874704801474944/t0(0) o400->MGC192.168.204.146@tcp@192.168.204.146@tcp:26/25 lens 224/224 e 0 to 1 dl 1787858138 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [11165.191617] LustreError: MGC192.168.204.146@tcp: Connection to MGS (at 192.168.204.146@tcp) was lost; in progress operations using this service will fail [11175.412929] Lustre: Evicted from MGS (at 192.168.204.146@tcp) after server handle changed from 0x8dc0795ec692856a to 0x8dc0795ec69287e0 [11175.424635] Lustre: MGC192.168.204.146@tcp: Connection restored to 192.168.204.146@tcp (at 192.168.204.146@tcp) [11176.897500] Lustre: lustre-MDT0000-mdc-ffff9f3602a37000: Connection restored to 192.168.204.146@tcp (at 192.168.204.146@tcp) [11191.625830] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11193.648395] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [11197.964366] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [11206.139947] Lustre: lustre-MDT0000-mdc-ffff9f3602a37000: Connection to lustre-MDT0000 (at 192.168.204.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [11222.434452] Lustre: 215346:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787858179/real 1787858179] req@00000000a9cf04ec x1874704801484800/t0(0) o400->MGC192.168.204.146@tcp@192.168.204.146@tcp:26/25 lens 224/224 e 0 to 1 dl 1787858195 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [11222.493408] LustreError: MGC192.168.204.146@tcp: Connection to MGS (at 192.168.204.146@tcp) was lost; in progress operations using this service will fail [11222.543812] Lustre: Evicted from MGS (at 192.168.204.146@tcp) after server handle changed from 0x8dc0795ec69287e0 to 0x8dc0795ec69289f4 [11222.563274] Lustre: MGC192.168.204.146@tcp: Connection restored to 192.168.204.146@tcp (at 192.168.204.146@tcp) [11233.774804] Lustre: lustre-MDT0000-mdc-ffff9f3602a37000: Connection restored to 192.168.204.146@tcp (at 192.168.204.146@tcp) [11249.390394] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11251.168750] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [11255.944439] Lustre: Unmounted lustre-client [11340.924888] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11348.778554] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11375.683740] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11381.861291] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [11384.930570] Lustre: Unmounted lustre-client [11416.780673] Key type lgssc unregistered [11417.081292] LNet: 225942:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11417.092957] LNetError: 225942:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11417.135772] LNet: Removed LNI 192.168.204.46@tcp [11418.582386] Key type .llcrypt unregistered [11418.584224] Key type ._llcrypt unregistered [11443.472205] Key type ._llcrypt registered [11443.487666] Key type .llcrypt registered [11445.336986] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 15:20:16 (1787858416) [11450.360837] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11450.418710] alg: No test for adler32 (adler32-zlib) [11451.531819] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [11451.904630] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [11453.736233] Key type lgssc registered [11455.250948] Lustre: Echo OBD driver; http://www.lustre.org/ [11518.414475] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11524.792705] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11550.445792] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11557.006430] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [11565.173990] Lustre: Unmounted lustre-client [11641.622730] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11648.633424] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11672.203743] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11683.384462] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [11693.281970] Lustre: Unmounted lustre-client [11770.151991] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11777.217114] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11804.025621] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11809.846986] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [11812.210183] Lustre: Unmounted lustre-client [11842.200171] Key type lgssc unregistered [11842.446749] LNet: 233001:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11842.457173] LNetError: 233001:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11842.469607] LNet: Removed LNI 192.168.204.46@tcp [11843.102625] Key type .llcrypt unregistered [11843.106553] Key type ._llcrypt unregistered [11858.542989] Key type ._llcrypt registered [11858.544600] Key type .llcrypt registered [11859.921716] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 15:27:11 (1787858831) [11863.541716] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11863.591185] alg: No test for adler32 (adler32-zlib) [11864.651096] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [11864.861105] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [11866.512408] Key type lgssc registered [11867.940669] Lustre: Echo OBD driver; http://www.lustre.org/ [11917.244856] Key type lgssc unregistered [11917.515682] LNet: 235329:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11917.533797] LNetError: 235329:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11917.560489] LNet: Removed LNI 192.168.204.46@tcp [11918.257519] Key type .llcrypt unregistered [11918.262529] Key type ._llcrypt unregistered [11946.733373] Key type ._llcrypt registered [11946.738482] Key type .llcrypt registered [11947.129438] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11947.157631] alg: No test for adler32 (adler32-zlib) [11948.220391] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [11948.448725] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [11950.113355] Key type lgssc registered [11951.198444] Lustre: Echo OBD driver; http://www.lustre.org/ [12051.146617] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12058.685646] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12084.436585] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12128.160383] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [12130.633299] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 15:31:42 (1787859102) [12169.849941] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12176.672222] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12200.797495] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12226.617684] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12228.579978] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [12233.704932] Lustre: lustre-OST0001-osc-ffff9f3620f81800: Connection to lustre-OST0001 (at 192.168.204.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [12240.590034] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12250.902585] Lustre: Unmounted lustre-client [12304.899116] Key type lgssc unregistered [12305.150947] LNet: 242455:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12305.162703] LNetError: 242455:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12305.215775] LNet: Removed LNI 192.168.204.46@tcp [12306.077342] Key type .llcrypt unregistered [12306.091964] Key type ._llcrypt unregistered [12344.650673] Key type ._llcrypt registered [12344.654060] Key type .llcrypt registered [12346.360418] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 15:35:17 (1787859317) [12362.276150] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12362.299559] alg: No test for adler32 (adler32-zlib) [12363.438477] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [12363.826123] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [12365.544210] Key type lgssc registered [12367.236247] Lustre: Echo OBD driver; http://www.lustre.org/ [12418.712644] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12423.760572] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12446.594908] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 15:36:57 (1787859417) [12483.361274] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12489.503181] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12511.322818] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12514.451362] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [12525.597906] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [12531.864082] Lustre: Unmounted lustre-client [12639.433308] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12646.615253] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12704.281580] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12705.187739] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [12708.612995] Lustre: Unmounted lustre-client [12743.562635] Key type lgssc unregistered [12743.929408] LNet: 250166:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12743.936156] LNetError: 250166:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12743.951477] LNet: Removed LNI 192.168.204.46@tcp [12744.999876] Key type .llcrypt unregistered [12745.004825] Key type ._llcrypt unregistered [12775.813888] Key type ._llcrypt registered [12775.830105] Key type .llcrypt registered [12776.085779] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12776.096279] alg: No test for adler32 (adler32-zlib) [12777.233454] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [12777.527042] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [12779.232579] Key type lgssc registered [12780.479591] Lustre: Echo OBD driver; http://www.lustre.org/ [12870.314954] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12876.737902] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12907.232688] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12954.277470] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [12957.001765] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 15:45:28 (1787859928) [12992.488195] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12997.741633] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13025.473784] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13032.911636] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [13037.045461] Lustre: Unmounted lustre-client [13066.748369] Key type lgssc unregistered [13067.106092] LNet: 256803:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13067.123196] LNetError: 256803:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13068.199146] LNet: Removed LNI 192.168.204.46@tcp [13068.880800] Key type .llcrypt unregistered [13068.884929] Key type ._llcrypt unregistered [13085.039662] Key type ._llcrypt registered [13085.044293] Key type .llcrypt registered [13086.753807] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [13088.502979] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 15:47:40 (1787860060) [13099.013349] Key type .llcrypt unregistered [13099.021057] Key type ._llcrypt unregistered [13108.295209] Key type ._llcrypt registered [13108.297852] Key type .llcrypt registered [13108.588032] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13108.604229] alg: No test for adler32 (adler32-zlib) [13109.670119] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [13109.935320] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [13111.672462] Key type lgssc registered [13112.796753] Lustre: Echo OBD driver; http://www.lustre.org/ [13172.870416] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13190.270884] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [13193.750637] Lustre: Unmounted lustre-client [13219.572703] Key type lgssc unregistered [13219.891919] LNet: 260268:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13219.909601] LNetError: 260268:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13219.950630] LNet: Removed LNI 192.168.204.46@tcp [13220.876793] Key type .llcrypt unregistered [13220.882793] Key type ._llcrypt unregistered [13240.009527] Key type ._llcrypt registered [13240.012481] Key type .llcrypt registered [13241.512593] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 15:50:12 (1787860212) [13244.823702] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13244.894228] alg: No test for adler32 (adler32-zlib) [13246.205971] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [13246.499928] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [13248.256929] Key type lgssc registered [13250.251247] Lustre: Echo OBD driver; http://www.lustre.org/ [13340.140773] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13378.530323] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13385.793371] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13389.063074] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [13392.248747] Lustre: Unmounted lustre-client [13421.894890] Key type lgssc unregistered [13422.260955] LNet: 263813:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13422.272792] LNetError: 263813:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13422.296854] LNet: Removed LNI 192.168.204.46@tcp [13423.148450] Key type .llcrypt unregistered [13423.152097] Key type ._llcrypt unregistered [13442.021046] Key type ._llcrypt registered [13442.023352] Key type .llcrypt registered [13443.513693] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 15:53:35 (1787860415) [13445.768310] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13445.854076] alg: No test for adler32 (adler32-zlib) [13447.048802] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [13447.301169] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [13449.016343] Key type lgssc registered [13450.759391] Lustre: Echo OBD driver; http://www.lustre.org/ [13511.596453] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13518.732324] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13543.267226] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13550.144692] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [13555.177378] Lustre: lustre-MDT0000-mdc-ffff9f3610788800: Connection to lustre-MDT0000 (at 192.168.204.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [13560.960992] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [13561.011229] LustreError: 266974:0:(file.c:6184:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [13561.640641] LustreError: 266993:0:(file.c:6184:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [13561.658439] LustreError: 266993:0:(file.c:6184:ll_inode_revalidate_fini()) Skipped 5 previous similar messages [13561.752134] LustreError: MGC192.168.204.146@tcp: Connection to MGS (at 192.168.204.146@tcp) was lost; in progress operations using this service will fail [13561.798325] Lustre: Unmounted lustre-client [13582.788988] Key type lgssc unregistered [13583.055184] LNet: 267391:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13583.062374] LNetError: 267391:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13583.098011] LNet: Removed LNI 192.168.204.46@tcp [13583.626553] Key type .llcrypt unregistered [13583.630302] Key type ._llcrypt unregistered [13603.491232] Key type ._llcrypt registered [13603.493506] Key type .llcrypt registered [13605.649855] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 15:56:16 (1787860576) [13609.741683] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13609.781710] alg: No test for adler32 (adler32-zlib) [13611.264372] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [13611.701339] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [13613.480202] Key type lgssc registered [13614.971918] Lustre: Echo OBD driver; http://www.lustre.org/ [13673.702835] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13680.680291] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13706.131730] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13713.495723] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [13718.521675] Lustre: lustre-MDT0001-mdc-ffff9f36050d0800: Connection to lustre-MDT0001 (at 192.168.204.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [13732.645280] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [13735.563052] Lustre: Unmounted lustre-client [13757.419157] Key type lgssc unregistered [13757.758633] LNet: 270974:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13757.780037] LNetError: 270974:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13758.824712] LNet: Removed LNI 192.168.204.46@tcp [13759.407189] Key type .llcrypt unregistered [13759.409339] Key type ._llcrypt unregistered [13777.880774] Key type ._llcrypt registered [13777.885238] Key type .llcrypt registered [13779.629593] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 15:59:10 (1787860750) [13799.952326] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13800.043259] alg: No test for adler32 (adler32-zlib) [13801.299425] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [13801.659057] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [13803.313554] Key type lgssc registered [13805.089871] Lustre: Echo OBD driver; http://www.lustre.org/ [13927.565915] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13930.343342] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [13981.168890] Lustre: lustre-MDT0001-mdc-ffff9f3604d7f800: Connection to lustre-MDT0001 (at 192.168.204.146@tcp) was lost; in progress operations using this service will wait for recovery to complete [13989.785841] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [14000.098691] Lustre: Unmounted lustre-client [14028.050653] Key type lgssc unregistered [14028.318449] LNet: 276778:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14028.324932] LNetError: 276778:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14028.336916] LNet: Removed LNI 192.168.204.46@tcp [14029.017376] Key type .llcrypt unregistered [14029.021655] Key type ._llcrypt unregistered [14046.306790] Key type ._llcrypt registered [14046.309640] Key type .llcrypt registered [14047.480710] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 16:03:39 (1787861019) [14049.160846] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [14051.395190] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 16:03:42 (1787861022) [14053.204069] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [14055.184289] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 16:03:46 (1787861026) [14057.149465] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [14058.976610] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 16:03:50 (1787861030) [14061.228750] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [14063.588448] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 16:03:54 (1787861034) [14066.036978] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [14068.436972] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 16:03:59 (1787861039) [14078.003650] Key type .llcrypt unregistered [14078.007297] Key type ._llcrypt unregistered [14085.951450] Key type ._llcrypt registered [14085.953184] Key type .llcrypt registered [14086.568464] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14086.591157] alg: No test for adler32 (adler32-zlib) [14087.617818] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [14087.838281] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [14089.608686] Key type lgssc registered [14091.247580] Lustre: Echo OBD driver; http://www.lustre.org/ [14174.234967] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14179.976987] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14202.481646] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14204.087145] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [14206.870532] Lustre: Unmounted lustre-client [14246.349812] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 16:06:58 (1787861218) [14284.210414] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14290.116693] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14327.065045] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14332.980452] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [14335.199188] Lustre: Unmounted lustre-client [14388.899536] LNet: 1 local NIs in recovery (showing 1): 192.168.204.46@tcp [14389.620164] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 16:09:21 (1787861361) [15043.774163] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15050.028210] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15088.920850] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15212.750903] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 16:23:04 (1787862184) [15226.697931] Key type lgssc unregistered [15227.045780] LNet: 290323:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15227.055631] LNetError: 290323:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15227.077733] LNet: Removed LNI 192.168.204.46@tcp [15227.802470] Key type .llcrypt unregistered [15227.805481] Key type ._llcrypt unregistered [15338.344135] Key type ._llcrypt registered [15338.348106] Key type .llcrypt registered [15339.165212] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15339.197849] alg: No test for adler32 (adler32-zlib) [15340.534704] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [15341.099725] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [15342.928312] Key type lgssc registered [15345.149611] Lustre: Echo OBD driver; http://www.lustre.org/ [15456.074452] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15463.718743] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15494.166366] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15531.984072] Key type lgssc unregistered [15532.352374] LNet: 294029:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15532.359654] LNetError: 294029:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15532.406987] LNet: Removed LNI 192.168.204.46@tcp [15533.433407] Key type .llcrypt unregistered [15533.437992] Key type ._llcrypt unregistered [15573.452624] Key type ._llcrypt registered [15573.456691] Key type .llcrypt registered [15574.811738] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 16:29:06 (1787862546) [15600.618053] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15600.799301] alg: No test for adler32 (adler32-zlib) [15601.957233] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [15602.308385] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [15603.992231] Key type lgssc registered [15605.254086] Lustre: Echo OBD driver; http://www.lustre.org/ [15661.788204] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 16:30:32 (1787862632) [15675.904283] Key type lgssc unregistered [15676.129214] LNet: 297596:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15676.136929] LNetError: 297596:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15676.153112] LNet: Removed LNI 192.168.204.46@tcp [15676.914611] Key type .llcrypt unregistered [15676.922235] Key type ._llcrypt unregistered [15690.835055] Key type ._llcrypt registered [15690.843641] Key type .llcrypt registered [15691.415695] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15691.438258] alg: No test for adler32 (adler32-zlib) [15692.474955] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [15692.806880] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [15694.504705] Key type lgssc registered [15696.114363] Lustre: Echo OBD driver; http://www.lustre.org/ [15759.938830] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15765.912610] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15792.451712] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16117.659757] LNet: Added LNI 192.168.204.46@tcp50 [8/256/0/180] [16119.714793] LNet: Removed LNI 192.168.204.46@tcp [16122.208763] Lustre: 302020:0:(ldlm_lib.c:571:client_obd_setup()) lustre-MDT0000-mdc-ffff9f3602a32800: config has no valid NIDs, force dynamic NIDs [16123.370517] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [16123.481462] Lustre: Unmounted lustre-client [16126.196680] Key type lgssc unregistered [16126.560785] LNet: 302280:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16126.569272] LNetError: 302280:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16126.598162] LNet: Removed LNI 192.168.204.46@tcp50 [16127.485487] Key type .llcrypt unregistered [16127.497881] Key type ._llcrypt unregistered [16152.277516] Key type ._llcrypt registered [16152.312567] Key type .llcrypt registered [16152.902830] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [16152.932943] alg: No test for adler32 (adler32-zlib) [16154.337054] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [16154.809127] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [16156.744485] Key type lgssc registered [16158.534174] Lustre: Echo OBD driver; http://www.lustre.org/ [16221.482914] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16228.958513] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16237.122891] LNet: Added LNI 192.168.204.46@tcp50 [8/256/0/180] [16238.013095] LNet: Removed LNI 192.168.204.46@tcp [16239.217984] Lustre: 304976:0:(ldlm_lib.c:571:client_obd_setup()) lustre-MDT0000-mdc-ffff9f3605902800: config has no valid NIDs, force dynamic NIDs [16240.495407] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [16241.149245] Lustre: Unmounted lustre-client [16275.241884] Key type lgssc unregistered [16275.639342] LNet: 305848:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16275.652753] LNetError: 305848:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16275.668305] LNet: Removed LNI 192.168.204.46@tcp50 [16276.307122] Key type .llcrypt unregistered [16276.309979] Key type ._llcrypt unregistered [16288.372921] Key type ._llcrypt registered [16288.376264] Key type .llcrypt registered [16289.715703] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 16:41:01 (1787863261) [16308.828523] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16308.883901] alg: No test for adler32 (adler32-zlib) [16309.925881] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [16310.196313] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [16311.856192] Key type lgssc registered [16313.079341] Lustre: Echo OBD driver; http://www.lustre.org/ [16401.311844] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16407.590151] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16435.577747] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16469.546783] Key type lgssc unregistered [16469.809876] LNet: 310602:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16469.817661] LNetError: 310602:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16469.841333] LNet: Removed LNI 192.168.204.46@tcp [16470.447033] Key type .llcrypt unregistered [16470.448807] Key type ._llcrypt unregistered [16480.315947] Key type ._llcrypt registered [16480.318190] Key type .llcrypt registered [16481.683518] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 16:44:13 (1787863453) [16484.870670] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16484.887700] alg: No test for adler32 (adler32-zlib) [16486.053565] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [16486.389218] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [16488.144459] Key type lgssc registered [16489.547968] Lustre: Echo OBD driver; http://www.lustre.org/ [16542.598965] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16548.583054] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16570.690645] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16573.470513] Lustre: Mounted lustre-client - version 2.17.57_87_ge30c52f [16636.169229] Lustre: Mounted fs15246-client - version 2.17.57_87_ge30c52f [16650.424361] Lustre: Unmounted fs15246-client [16675.620579] Lustre: Unmounted lustre-client [16709.499767] Key type lgssc unregistered [16709.784406] LNet: 315461:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16709.794941] LNetError: 315461:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16710.828296] LNet: Removed LNI 192.168.204.46@tcp [16711.531825] Key type .llcrypt unregistered [16711.535191] Key type ._llcrypt unregistered [16722.240291] Key type ._llcrypt registered [16722.243313] Key type .llcrypt registered [16723.482470] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 16:48:15 (1787863695) [16724.811528] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16724.835888] alg: No test for adler32 (adler32-zlib) [16725.937371] Lustre: Lustre: Build Version: 2.17.57_87_ge30c52f [16726.180363] LNet: Added LNI 192.168.204.46@tcp [8/256/0/180] [16727.912201] Key type lgssc registered [16729.060302] Lustre: Echo OBD driver; http://www.lustre.org/ [16865.309369] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16871.772749] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16898.926856] Lustre: DEBUG MARKER: oleg446-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [17018.625187] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 16704 sec ========= 16:53:09 (1787863989) [17020.812789] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 16:53:12 (1787863992) === [17024.640298] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 16:53:16 (1787863996) === [17060.584962] Key type lgssc unregistered [17060.831850] LNet: 324728:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [17060.837334] LNetError: 324728:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [17060.849780] LNet: Removed LNI 192.168.204.46@tcp [17061.399782] Key type .llcrypt unregistered [17061.404335] Key type ._llcrypt unregistered