[ 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 475895511 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001016] APIC: Switch to symmetric I/O mode setup [ 0.003074] x2apic enabled [ 0.004008] Switched APIC routing to physical x2apic. [ 0.005019] kvm-guest: setup PV IPIs [ 0.008514] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.009000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.010033] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.011019] pid_max: default: 32768 minimum: 301 [ 0.013037] LSM: Security Framework initializing [ 0.015084] Yama: becoming mindful. [ 0.016045] SELinux: Initializing. [ 0.017101] *** VALIDATE selinux *** [ 0.025524] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.030253] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.031166] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.032112] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.033133] *** VALIDATE tmpfs *** [ 0.035364] *** VALIDATE proc *** [ 0.036299] *** VALIDATE cgroup *** [ 0.037015] *** VALIDATE cgroup2 *** [ 0.038287] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.041090] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.042014] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.043049] Spectre V2 : User space: Vulnerable [ 0.044014] Speculative Store Bypass: Vulnerable [ 0.047104] debug: unmapping init [mem 0xffffffff8b259000-0xffffffff8b260fff] [ 0.049845] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.050816] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.051029] ... version: 2 [ 0.052017] ... bit width: 48 [ 0.053017] ... generic registers: 4 [ 0.054016] ... value mask: 0000ffffffffffff [ 0.055019] ... max period: 00007fffffffffff [ 0.056018] ... fixed-purpose events: 3 [ 0.057016] ... event mask: 000000070000000f [ 0.059298] rcu: Hierarchical SRCU implementation. [ 0.061463] smp: Bringing up secondary CPUs ... [ 0.062906] x86: Booting SMP configuration: [ 0.063038] .... node #0, CPUs: #1 #2 #3 [ 0.067443] smp: Brought up 1 node, 4 CPUs [ 0.069025] smpboot: Max logical packages: 1 [ 0.070020] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.235389] node 0 deferred pages initialised in 162ms [ 0.240148] devtmpfs: initialized [ 0.241324] x86/mm: Memory block size: 128MB [ 0.244045] gcov: version magic: 0x41383552 [ 0.247306] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.251124] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.253308] pinctrl core: initialized pinctrl subsystem [ 0.256205] [ 0.256854] ************************************************************* [ 0.260015] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.263011] ** ** [ 0.265012] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.269018] ** ** [ 0.272015] ** This means that this kernel is built to expose internal ** [ 0.274012] ** IOMMU data structures, which may compromise security on ** [ 0.276017] ** your system. ** [ 0.278015] ** ** [ 0.281018] ** If you see this message and you are not debugging the ** [ 0.283013] ** kernel, report this immediately to your vendor! ** [ 0.287017] ** ** [ 0.290014] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.292023] ************************************************************* [ 0.295713] NET: Registered protocol family 16 [ 0.297435] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.300067] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.303063] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.307185] cpuidle: using governor menu [ 0.308737] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.309622] PCI: Using configuration type 1 for base access [ 0.311131] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.322089] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.325030] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.330080] cryptd: max_cpu_qlen set to 1000 [ 0.333284] ACPI: Added _OSI(Module Device) [ 0.335019] ACPI: Added _OSI(Processor Device) [ 0.337015] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.339017] ACPI: Added _OSI(Processor Aggregator Device) [ 0.344365] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.351610] ACPI: Interpreter enabled [ 0.353055] ACPI: PM: (supports S0 S3 S4 S5) [ 0.355013] ACPI: Using IOAPIC for interrupt routing [ 0.356115] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.359476] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.369510] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.371041] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.373017] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.377075] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.381197] acpiphp: Slot [2] registered [ 0.383117] acpiphp: Slot [5] registered [ 0.385100] acpiphp: Slot [6] registered [ 0.386124] acpiphp: Slot [3] registered [ 0.387089] acpiphp: Slot [4] registered [ 0.389126] acpiphp: Slot [7] registered [ 0.391106] acpiphp: Slot [8] registered [ 0.392129] acpiphp: Slot [9] registered [ 0.394109] acpiphp: Slot [10] registered [ 0.395138] acpiphp: Slot [11] registered [ 0.397107] acpiphp: Slot [12] registered [ 0.398150] acpiphp: Slot [13] registered [ 0.399108] acpiphp: Slot [14] registered [ 0.401176] acpiphp: Slot [15] registered [ 0.402130] acpiphp: Slot [16] registered [ 0.404101] acpiphp: Slot [17] registered [ 0.406111] acpiphp: Slot [18] registered [ 0.407106] acpiphp: Slot [19] registered [ 0.409115] acpiphp: Slot [20] registered [ 0.410111] acpiphp: Slot [21] registered [ 0.411181] acpiphp: Slot [22] registered [ 0.412066] acpiphp: Slot [23] registered [ 0.414100] acpiphp: Slot [24] registered [ 0.415115] acpiphp: Slot [25] registered [ 0.417101] acpiphp: Slot [26] registered [ 0.418130] acpiphp: Slot [27] registered [ 0.420115] acpiphp: Slot [28] registered [ 0.421118] acpiphp: Slot [29] registered [ 0.423100] acpiphp: Slot [30] registered [ 0.427137] acpiphp: Slot [31] registered [ 0.428090] PCI host bridge to bus 0000:00 [ 0.429020] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.431024] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.433024] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.435021] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.437081] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.439033] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.441171] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.443911] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.446294] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.452586] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.457070] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.459035] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.461017] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.463021] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.465635] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.469259] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.472062] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.475749] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.480014] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.489694] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.494017] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.499600] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.505016] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.510017] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.524843] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.533092] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.543017] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.552017] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.572019] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.586660] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.589409] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.592369] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.594405] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.596212] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.601102] iommu: Default domain type: Passthrough [ 0.605581] SCSI subsystem initialized [ 0.607166] ACPI: bus type USB registered [ 0.609290] usbcore: registered new interface driver usbfs [ 0.612100] usbcore: registered new interface driver hub [ 0.614104] usbcore: registered new device driver usb [ 0.616175] pps_core: LinuxPPS API ver. 1 registered [ 0.618017] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.621061] PTP clock support registered [ 0.624069] EDAC MC: Ver: 3.0.0 [ 0.626124] PCI: Using ACPI for IRQ routing [ 0.630564] NetLabel: Initializing [ 0.633015] NetLabel: domain hash size = 128 [ 0.635013] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.637108] NetLabel: unlabeled traffic allowed by default [ 0.641082] vgaarb: loaded [ 0.642283] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.644017] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.649330] clocksource: Switched to clocksource kvm-clock [ 0.756500] VFS: Disk quotas dquot_6.6.0 [ 0.758443] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.761097] *** VALIDATE ramfs *** [ 0.762410] *** VALIDATE hugetlbfs *** [ 0.764141] pnp: PnP ACPI init [ 0.766561] pnp: PnP ACPI: found 6 devices [ 0.782713] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.786764] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.790577] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.794448] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.798524] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.802684] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.807380] NET: Registered protocol family 2 [ 0.811568] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.819606] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.824134] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.829396] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.835708] TCP: Hash tables configured (established 65536 bind 65536) [ 0.839884] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.844104] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.847980] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.853036] NET: Registered protocol family 1 [ 0.856738] RPC: Registered named UNIX socket transport module. [ 0.859424] RPC: Registered udp transport module. [ 0.861657] RPC: Registered tcp transport module. [ 0.864269] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.867545] NET: Registered protocol family 44 [ 0.869339] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.871588] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.873722] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.875918] PCI: CLS 0 bytes, default 64 [ 0.877507] Unpacking initramfs... [ 2.297746] debug: unmapping init [mem 0xffff8d35fcc64000-0xffff8d35fffcffff] [ 2.301981] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.304347] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.307461] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.814404] Initialise system trusted keyrings [ 2.817777] Key type blacklist registered [ 2.819742] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.831492] zbud: loaded [ 2.835669] *** VALIDATE nfs *** [ 2.837621] *** VALIDATE nfs4 *** [ 2.840080] pstore: using deflate compression [ 2.844925] Platform Keyring initialized [ 2.965795] NET: Registered protocol family 38 [ 2.967896] Key type asymmetric registered [ 2.969539] Asymmetric key parser 'x509' registered [ 2.971570] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.975244] io scheduler mq-deadline registered [ 2.976773] io scheduler kyber registered [ 2.978921] io scheduler bfq registered [ 2.980860] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.984126] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.987115] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.990166] ACPI: Power Button [PWRF] [ 2.995621] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.001793] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.014981] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.041753] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.073715] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.079284] Non-volatile memory driver v1.3 [ 3.081603] Linux agpgart interface v0.103 [ 3.110219] virtio_blk virtio1: [vda] 146672 512-byte logical blocks (75.1 MB/71.6 MiB) [ 3.113645] vda: detected capacity change from 0 to 75096064 [ 3.128206] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.130838] vdb: detected capacity change from 0 to 1073741824 [ 3.137910] libphy: Fixed MDIO Bus: probed [ 3.144949] usbcore: registered new interface driver usbserial_generic [ 3.148052] usbserial: USB Serial support registered for generic [ 3.150418] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.155799] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.157677] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.160179] mousedev: PS/2 mouse device common for all mice [ 3.163076] rtc_cmos 00:05: RTC can wake from S4 [ 3.166583] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.171346] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.171688] rtc_cmos 00:05: registered as rtc0 [ 3.175806] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.175959] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.178213] intel_pstate: CPU model not supported [ 3.184366] hid: raw HID events driver (C) Jiri Kosina [ 3.186642] usbcore: registered new interface driver usbhid [ 3.189235] usbhid: USB HID core driver [ 3.191112] drop_monitor: Initializing network drop monitor service [ 3.194188] Initializing XFRM netlink socket [ 3.196385] NET: Registered protocol family 10 [ 3.200345] Segment Routing with IPv6 [ 3.201886] NET: Registered protocol family 17 [ 3.204079] mpls_gso: MPLS GSO support [ 3.208956] RAS: Correctable Errors collector initialized. [ 3.211503] AVX version of gcm_enc/dec engaged. [ 3.213561] AES CTR mode by8 optimization enabled [ 3.295075] sched_clock: Marking stable (3295028696, 0)->(4261005970, -965977274) [ 3.298983] registered taskstats version 1 [ 3.301077] Loading compiled-in X.509 certificates [ 3.303146] zswap: loaded using pool lzo/zbud [ 3.328119] Key type big_key registered [ 3.343164] Key type encrypted registered [ 3.344990] ima: No TPM chip found, activating TPM-bypass! [ 3.347534] ima: Allocated hash algorithm: sha1 [ 3.349380] ima: No architecture policies found [ 3.351292] evm: Initialising EVM extended attributes: [ 3.352762] evm: security.selinux [ 3.354119] evm: security.ima [ 3.355505] evm: security.capability [ 3.357159] evm: HMAC attrs: 0x1 [ 3.360630] rtc_cmos 00:05: setting system clock to 2026-08-27 10:49:59 UTC (1787827799) [ 3.367238] debug: unmapping init [mem 0xffffffff8c203000-0xffffffff8c3fffff] [ 3.370219] debug: unmapping init [mem 0xffffffff8af82000-0xffffffff8b258fff] [ 3.380097] Write protecting the kernel read-only data: 28672k [ 3.385675] debug: unmapping init [mem 0xffffffff89603000-0xffffffff897fffff] [ 3.387814] debug: unmapping init [mem 0xffffffff89f14000-0xffffffff89ffffff] [ 3.423036] 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.431693] systemd[1]: Detected virtualization kvm. [ 3.433567] systemd[1]: Detected architecture x86-64. [ 3.435170] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.463930] systemd[1]: No hostname configured. [ 3.465794] systemd[1]: Set hostname to . [ 3.467682] random: systemd: uninitialized urandom read (16 bytes read) [ 3.469736] systemd[1]: Initializing machine ID from random generator. [ 3.517906] random: ln: uninitialized urandom read (6 bytes read) [ 3.614546] random: systemd: uninitialized urandom read (16 bytes read) [ 3.618777] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ 3.623277] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 3.628429] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Slices. Starting Journal Service... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Swap. [ OK ] Reached target Initrd Root Device. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. Starting Setup Virtual Console... [ OK ] Reached target Paths. Starting Apply Kernel Variables... [ OK ] Reached target Local Encrypted Volumes. [ OK ] Started Memstrack Anylazing Service. [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Journal Service. [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.332705] device-mapper: uevent: version 1.0.3 [ 4.335133] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 5.183514] virtio_net virtio0 ens2: renamed from eth0 [ 5.232982] scsi host0: ata_piix [ 5.287297] scsi host1: ata_piix [ 5.289001] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.291887] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.038622] dracut-initqueue[576]: RTNETLINK answers: File exists [ 9.863571] random: crng init done [ 9.866552] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 10.374225] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Swap. [ 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 Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.558674] printk: systemd: 17 output lines suppressed due to ratelimiting [ 11.846126] SELinux: Disabled at runtime. [ 11.903281] 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.912672] systemd[1]: Detected virtualization kvm. [ 11.914681] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.491716] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.494955] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.499176] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.506275] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.510577] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.519554] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.527373] systemd[1]: proc-sys-fs-binfmt_misc.automount: Refusing to start, unit to trigger not loaded. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Stopped target Switch Root. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on Process Core Dump Socket. Mounting Kernel Debug File System... Starting Remount Root and Kernel File Systems... [ OK ] Stopped target Initrd File Systems. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Reached target Paths. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Stopped target Initrd Root File System. [ OK ] Listening on udev Control Socket. Mounting Huge Pages File System... [ OK ] Reached target Local Encrypted Volumes. [ OK ] Created slice system-getty.slice. [ OK ] Reached target rpc_pipefs.target. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. Starting Apply Kernel Variables... Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Reached target RPC Port Mapper. Mounting POSIX Message Queue File System... [ 12.686342] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Started Journal Service. [ OK ] Mounted Kernel Debug File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Huge Pages File System. [ OK ] Started Apply Kernel Variables. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 12.973284] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 13.369836] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.410996] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.513410] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.546860] EDAC sbridge: Ver: 1.1.2 [ 14.695441] Key type dns_resolver registered [ 15.040386] NFS: Registering the id_resolver key type [ 15.042334] Key type id_resolver registered [ 15.043880] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started dnf makecache --timer. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Restore /run/initramfs on shutdown... Starting Login Service... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started irqbalance daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Started OpenSSH server daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... Starting Hostname Service... [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... [ 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 oleg644-client login: [ 65.277830] libcfs: loading out-of-tree module taints kernel. [ 65.343669] Key type ._llcrypt registered [ 65.347280] Key type .llcrypt registered [ 65.797870] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 65.825345] alg: No test for adler32 (adler32-zlib) [ 67.719866] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 68.918632] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [ 70.695162] Key type lgssc registered [ 72.131858] Lustre: Echo OBD driver; http://www.lustre.org/ [ 236.219643] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 240.935616] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 256.414046] hrtimer: interrupt took 1536776 ns [ 258.533404] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing check_logdir /tmp/testlogs/ [ 262.113886] Lustre: lustre-OST0000-osc-ffff8d36452cb000: disconnect after 24s idle [ 264.030413] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing yml_node [ 268.916763] Lustre: DEBUG MARKER: Client: 2.17.57.65 [ 271.390188] Lustre: DEBUG MARKER: MDS: 2.17.57.65 [ 273.727637] Lustre: DEBUG MARKER: OSS: 2.17.57.65 [ 275.180744] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Thu Aug 27 06:54:30 EDT 2026 [ 289.884777] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 291.359815] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 293.866996] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 304.229784] Lustre: Unmounted lustre-client [ 417.606618] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 422.862848] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 445.280284] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 478.730295] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 06:57:53 (1787828273) [ 480.569540] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 482.176644] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 06:57:57 (1787828277) [ 511.685693] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 516.702604] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 533.841637] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 534.365637] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 538.684808] Lustre: Unmounted lustre-client [ 564.945555] Key type lgssc unregistered [ 565.211863] LNet: 15053:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 565.226336] LNetError: 15053:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 565.243817] LNet: Removed LNI 192.168.206.44@tcp [ 565.929146] Key type .llcrypt unregistered [ 565.931378] Key type ._llcrypt unregistered [ 579.865863] Key type ._llcrypt registered [ 579.867854] Key type .llcrypt registered [ 580.972939] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 06:59:35 (1787828375) [ 582.374558] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 582.392449] alg: No test for adler32 (adler32-zlib) [ 583.443581] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 583.720165] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [ 585.392099] Key type lgssc registered [ 586.414901] Lustre: Echo OBD driver; http://www.lustre.org/ [ 655.405074] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 746.581544] Lustre: Unmounted lustre-client [ 772.811420] Key type lgssc unregistered [ 773.083148] LNet: 18883:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 773.093276] LNetError: 18883:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 773.122541] LNet: Removed LNI 192.168.206.44@tcp [ 773.799090] Key type .llcrypt unregistered [ 773.804165] Key type ._llcrypt unregistered [ 783.074684] Key type ._llcrypt registered [ 783.077433] Key type .llcrypt registered [ 784.216135] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 07:02:59 (1787828579) [ 786.353576] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 786.394098] alg: No test for adler32 (adler32-zlib) [ 787.445420] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 787.657469] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [ 789.359138] Key type lgssc registered [ 790.590447] Lustre: Echo OBD driver; http://www.lustre.org/ [ 838.017171] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 842.984614] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 861.198106] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 864.091914] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 873.618034] Lustre: Unmounted lustre-client [ 900.657406] Key type lgssc unregistered [ 900.895883] LNet: 22502:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 900.900145] LNetError: 22502:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 900.913367] LNet: Removed LNI 192.168.206.44@tcp [ 901.567466] Key type .llcrypt unregistered [ 901.570140] Key type ._llcrypt unregistered [ 913.396452] Key type ._llcrypt registered [ 913.398020] Key type .llcrypt registered [ 914.686739] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 07:05:09 (1787828709) [ 917.385920] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 917.402906] alg: No test for adler32 (adler32-zlib) [ 918.806309] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 919.202550] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [ 920.904587] Key type lgssc registered [ 922.440527] Lustre: Echo OBD driver; http://www.lustre.org/ [ 975.234892] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 980.898212] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1001.506384] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1004.410079] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 1013.157355] Lustre: Unmounted lustre-client [ 1038.853532] Key type lgssc unregistered [ 1039.149712] LNet: 25698:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1039.167764] LNetError: 25698:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1039.197751] LNet: Removed LNI 192.168.206.44@tcp [ 1039.979816] Key type .llcrypt unregistered [ 1039.983628] Key type ._llcrypt unregistered [ 1056.782743] Key type ._llcrypt registered [ 1056.784789] Key type .llcrypt registered [ 1057.745565] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 07:07:32 (1787828852) [ 1059.835259] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1059.886763] alg: No test for adler32 (adler32-zlib) [ 1060.873556] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 1061.083534] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [ 1062.799433] Key type lgssc registered [ 1063.996544] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1110.843180] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1116.122858] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1135.522420] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1138.259193] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 1143.307853] Lustre: lustre-MDT0000-mdc-ffff8d36516d4000: Connection to lustre-MDT0000 (at 192.168.206.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1148.392890] Lustre: lustre-MDT0001-mdc-ffff8d36516d4000: Connection to lustre-MDT0001 (at 192.168.206.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1164.627886] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1164.767177] Lustre: 26656:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787828944/real 1787828944] req@000000003ade2072 x1874674431563264/t0(0) o400->MGC192.168.206.144@tcp@192.168.206.144@tcp:26/25 lens 224/224 e 0 to 1 dl 1787828960 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1164.799454] LustreError: MGC192.168.206.144@tcp: Connection to MGS (at 192.168.206.144@tcp) was lost; in progress operations using this service will fail [ 1174.972686] Lustre: Unmounted lustre-client [ 1187.406074] Key type lgssc unregistered [ 1187.671407] LNet: 29356:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1187.682816] LNetError: 29356:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1187.704347] LNet: Removed LNI 192.168.206.44@tcp [ 1188.360991] Key type .llcrypt unregistered [ 1188.362852] Key type ._llcrypt unregistered [ 1202.126750] Key type ._llcrypt registered [ 1202.128227] Key type .llcrypt registered [ 1203.539320] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 07:09:58 (1787828998) [ 1206.152105] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1206.174577] alg: No test for adler32 (adler32-zlib) [ 1207.298156] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 1207.597460] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [ 1209.311782] Key type lgssc registered [ 1210.717916] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1262.803645] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1267.999495] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1288.257185] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1290.922842] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 1303.067981] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1313.414525] Lustre: Unmounted lustre-client [ 1358.351625] Key type lgssc unregistered [ 1358.661738] LNet: 32976:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1358.679233] LNetError: 32976:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1358.696773] LNet: Removed LNI 192.168.206.44@tcp [ 1359.534969] Key type .llcrypt unregistered [ 1359.537908] Key type ._llcrypt unregistered [ 1376.721596] Key type ._llcrypt registered [ 1376.724561] Key type .llcrypt registered [ 1377.876558] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 07:12:52 (1787829172) [ 1380.108266] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1380.368732] alg: No test for adler32 (adler32-zlib) [ 1381.448667] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 1381.728800] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [ 1383.447187] Key type lgssc registered [ 1384.797985] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1439.158536] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1444.634066] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1464.550238] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1467.129339] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 1470.404827] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1473.754416] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1485.179714] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-08-27 7h14m39s [ 1487.310861] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1492.968790] Lustre: lustre-MDT0000-mdc-ffff8d3651e7c000: Connection to lustre-MDT0000 (at 192.168.206.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1497.394522] LustreError: 36764:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff8d3651e7c000: can't stat MDS #0: rc = -19 [ 1498.728881] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1509.361392] LustreError: MGC192.168.206.144@tcp: Connection to MGS (at 192.168.206.144@tcp) was lost; in progress operations using this service will fail [ 1509.380051] Lustre: Evicted from MGS (at 192.168.206.144@tcp) after server handle changed from 0x274d237c5a31b0c7 to 0x274d237c5a31b38a [ 1509.406753] Lustre: MGC192.168.206.144@tcp: Connection restored to 192.168.206.144@tcp (at 192.168.206.144@tcp) [ 1510.446541] LustreError: lustre-MDT0000-mdc-ffff8d3651e7c000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1510.460632] Lustre: lustre-MDT0000-mdc-ffff8d3651e7c000: Connection restored to 192.168.206.144@tcp (at 192.168.206.144@tcp) [ 1513.560268] Lustre: DEBUG MARKER: Wait for df (36764) ... [ 1515.137944] Lustre: DEBUG MARKER: done [ 1516.853520] Lustre: Unmounted lustre-client [ 1545.389811] Key type lgssc unregistered [ 1545.663600] LNet: 37783:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1545.675121] LNetError: 37783:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1545.698368] LNet: Removed LNI 192.168.206.44@tcp [ 1546.421666] Key type .llcrypt unregistered [ 1546.425876] Key type ._llcrypt unregistered [ 1576.415348] Key type ._llcrypt registered [ 1576.419533] Key type .llcrypt registered [ 1577.582747] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 07:16:12 (1787829372) [ 1579.698585] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1579.799647] alg: No test for adler32 (adler32-zlib) [ 1580.887407] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 1581.122717] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [ 1582.825570] Key type lgssc registered [ 1583.982771] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1631.365557] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1637.378483] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1659.235375] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1660.911325] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 1665.349636] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-08-27 7h17m40s [ 1667.057584] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1674.139569] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1676.950856] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1697.759163] Lustre: 41937:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787829473/real 1787829473] req@00000000245f29d0 x1874674976823936/t0(0) o101->lustre-MDT0000-mdc-ffff8d3647568800@192.168.206.144@tcp:12/10 lens 576/1152 e 0 to 1 dl 1787829493 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1697.788733] Lustre: lustre-MDT0000-mdc-ffff8d3647568800: Connection to lustre-MDT0000 (at 192.168.206.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1697.815058] Lustre: lustre-MDT0000-mdc-ffff8d3647568800: Connection restored to 192.168.206.144@tcp (at 192.168.206.144@tcp) [ 1719.035866] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1722.249305] Lustre: DEBUG MARKER: done [ 1726.848925] Lustre: Unmounted lustre-client [ 1754.958940] Key type lgssc unregistered [ 1755.282937] LNet: 42815:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1755.293444] LNetError: 42815:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1755.321223] LNet: Removed LNI 192.168.206.44@tcp [ 1755.956609] Key type .llcrypt unregistered [ 1755.964498] Key type ._llcrypt unregistered [ 1789.443808] Key type ._llcrypt registered [ 1789.445555] Key type .llcrypt registered [ 1791.094346] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 07:19:45 (1787829585) [ 1792.841977] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1794.221880] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 07:19:49 (1787829589) [ 1798.421345] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1798.462990] alg: No test for adler32 (adler32-zlib) [ 1799.659966] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 1799.893862] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [ 1801.703158] Key type lgssc registered [ 1803.185870] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1835.521533] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 07:20:29 (1787829629) [ 1872.469676] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1878.903632] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1900.268312] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1903.030128] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 1906.570110] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 1909.888884] Lustre: Unmounted lustre-client [ 1929.309317] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 1981.708809] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1988.416374] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1989.247628] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 1992.992432] Lustre: Unmounted lustre-client [ 2059.474285] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2065.731498] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2066.335849] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 2070.607503] Lustre: Unmounted lustre-client [ 2093.494673] Lustre: DEBUG MARKER: files compared the same [ 2115.825407] Key type lgssc unregistered [ 2116.042339] LNet: 50450:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2116.049462] LNetError: 50450:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2116.081983] LNet: Removed LNI 192.168.206.44@tcp [ 2116.729332] Key type .llcrypt unregistered [ 2116.733879] Key type ._llcrypt unregistered [ 2129.864560] Key type ._llcrypt registered [ 2129.868521] Key type .llcrypt registered [ 2130.965974] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 07:25:25 (1787829925) [ 2132.756318] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2132.777519] alg: No test for adler32 (adler32-zlib) [ 2133.783868] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 2134.042697] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [ 2135.679423] Key type lgssc registered [ 2136.748923] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2183.191380] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2188.322537] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2208.005367] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2209.636170] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 2213.645150] Lustre: Unmounted lustre-client [ 2249.455191] Key type lgssc unregistered [ 2249.698864] LNet: 54034:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2249.710286] LNetError: 54034:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2249.731265] LNet: Removed LNI 192.168.206.44@tcp [ 2250.447166] Key type .llcrypt unregistered [ 2250.455142] Key type ._llcrypt unregistered [ 2263.914200] Key type ._llcrypt registered [ 2263.916457] Key type .llcrypt registered [ 2265.201517] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 07:27:39 (1787830059) [ 2267.654229] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2267.678545] alg: No test for adler32 (adler32-zlib) [ 2269.007880] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 2269.351659] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [ 2271.103246] Key type lgssc registered [ 2272.392197] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2355.969949] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2396.338764] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2403.502251] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2432.813620] Key type lgssc unregistered [ 2433.060594] LNet: 57524:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2433.066340] LNetError: 57524:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2433.087530] LNet: Removed LNI 192.168.206.44@tcp [ 2433.954581] Key type .llcrypt unregistered [ 2433.957805] Key type ._llcrypt unregistered [ 2450.848756] Key type ._llcrypt registered [ 2450.851969] Key type .llcrypt registered [ 2452.081858] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 07:30:46 (1787830246) [ 2455.516131] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2455.531843] alg: No test for adler32 (adler32-zlib) [ 2456.558738] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 2456.805487] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [ 2458.463187] Key type lgssc registered [ 2459.491446] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2532.128722] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 2539.429813] Lustre: Unmounted lustre-client [ 2578.017782] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 07:32:52 (1787830372) [ 2707.619630] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2729.031857] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 2736.597697] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2736.612463] LustreError: 63997:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff8d3649a7e800: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 2736.635563] Lustre: 58508:0:(llite_lib.c:4342:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.206.144@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 2746.990916] Lustre: Unmounted lustre-client [ 2799.001484] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 07:36:33 (1787830593) [ 2809.189531] Key type lgssc unregistered [ 2809.463323] LNet: 65209:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2809.480754] LNetError: 65209:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2809.513941] LNet: Removed LNI 192.168.206.44@tcp [ 2810.365438] Key type .llcrypt unregistered [ 2810.368339] Key type ._llcrypt unregistered [ 2822.049516] Key type ._llcrypt registered [ 2822.088033] Key type .llcrypt registered [ 2822.458658] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2822.474664] alg: No test for adler32 (adler32-zlib) [ 2823.564211] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 2823.913238] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [ 2825.623316] Key type lgssc registered [ 2826.563576] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2874.419935] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2880.643912] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2900.926530] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2902.601229] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 2907.362887] Lustre: Unmounted lustre-client [ 2936.606540] Key type lgssc unregistered [ 2937.027455] LNet: 68224:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2937.046890] LNetError: 68224:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2938.086877] LNet: Removed LNI 192.168.206.44@tcp [ 2939.209393] Key type .llcrypt unregistered [ 2939.213995] Key type ._llcrypt unregistered [ 2953.453486] Key type ._llcrypt registered [ 2953.461972] Key type .llcrypt registered [ 2953.730055] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2953.744478] alg: No test for adler32 (adler32-zlib) [ 2955.044651] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 2955.425285] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [ 2957.136316] Key type lgssc registered [ 2958.426445] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3065.229228] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3070.616363] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3090.220143] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3091.790205] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 3094.571379] Lustre: Unmounted lustre-client [ 3132.637191] Key type lgssc unregistered [ 3132.937102] LNet: 71658:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3132.949560] LNetError: 71658:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3132.978545] LNet: Removed LNI 192.168.206.44@tcp [ 3133.647541] Key type .llcrypt unregistered [ 3133.658972] Key type ._llcrypt unregistered [ 3147.545841] Key type ._llcrypt registered [ 3147.548129] Key type .llcrypt registered [ 3148.868614] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 07:42:23 (1787830943) [ 3150.869389] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3150.910643] alg: No test for adler32 (adler32-zlib) [ 3151.962958] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 3152.199222] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [ 3153.922580] Key type lgssc registered [ 3154.979214] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3202.437851] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3208.474437] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3232.130755] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3234.946400] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 3243.316720] Lustre: Unmounted lustre-client [ 3243.661929] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 3246.471492] Lustre: Unmounted lustre-client [ 3274.228950] Key type lgssc unregistered [ 3274.505512] LNet: 75343:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3274.522614] LNetError: 75343:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3274.555427] LNet: Removed LNI 192.168.206.44@tcp [ 3275.311820] Key type .llcrypt unregistered [ 3275.322513] Key type ._llcrypt unregistered [ 3287.184911] Key type ._llcrypt registered [ 3287.199295] Key type .llcrypt registered [ 3287.725406] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3287.739863] alg: No test for adler32 (adler32-zlib) [ 3288.836185] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 3289.031685] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [ 3290.687312] Key type lgssc registered [ 3292.094653] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3341.847572] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3347.687691] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3366.409235] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3367.950774] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 3371.964925] Lustre: Unmounted lustre-client [ 3398.158794] Key type lgssc unregistered [ 3398.332215] LNet: 78389:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3398.340435] LNetError: 78389:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3398.353780] LNet: Removed LNI 192.168.206.44@tcp [ 3398.950988] Key type .llcrypt unregistered [ 3398.953864] Key type ._llcrypt unregistered [ 3412.534854] Key type ._llcrypt registered [ 3412.536917] Key type .llcrypt registered [ 3413.789441] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 07:46:48 (1787831208) [ 3416.162843] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3416.185978] alg: No test for adler32 (adler32-zlib) [ 3417.226226] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 3417.404343] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [ 3419.048137] Key type lgssc registered [ 3420.244258] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3463.768714] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3468.953579] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3488.228685] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3489.765087] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 3500.122960] Lustre: lustre: root_squash is set to 0:0 [ 3500.145878] Lustre: lustre: nosquash_nids is cleared [ 3508.758684] Lustre: lustre: root_squash is set to 500:500 [ 3516.748234] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.206.44@tcp 192.168.0.[2,10]@tcp [ 3523.586653] Lustre: lustre: nosquash_nids set to 192.168.206.44/24@tcp [ 3533.113676] Lustre: Unmounted lustre-client [ 3561.681664] Key type lgssc unregistered [ 3561.869334] LNet: 82361:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3561.886371] LNetError: 82361:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3561.917718] LNet: Removed LNI 192.168.206.44@tcp [ 3562.637454] Key type .llcrypt unregistered [ 3562.641220] Key type ._llcrypt unregistered [ 3578.503254] Key type ._llcrypt registered [ 3578.505731] Key type .llcrypt registered [ 3579.707863] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 07:49:34 (1787831374) [ 3582.408830] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3583.984643] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 07:49:38 (1787831378) [ 3586.159650] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3586.390818] alg: No test for adler32 (adler32-zlib) [ 3587.388910] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 3587.603150] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [ 3589.280252] Key type lgssc registered [ 3590.633062] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3635.450645] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3639.788913] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3656.436736] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3656.825465] Lustre: lustre: root_squash is set to 500:500 [ 3656.848756] Lustre: lustre: nosquash_nids set to 192.168.206.44/24@tcp [ 3662.364425] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 3667.800131] Lustre: Unmounted lustre-client [ 3694.080449] Key type lgssc unregistered [ 3694.312200] LNet: 86325:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3694.317731] LNetError: 86325:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3694.338765] LNet: Removed LNI 192.168.206.44@tcp [ 3695.108164] Key type .llcrypt unregistered [ 3695.110853] Key type ._llcrypt unregistered [ 3709.115695] Key type ._llcrypt registered [ 3709.118322] Key type .llcrypt registered [ 3710.447740] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 3712.063883] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 07:51:46 (1787831506) [ 3729.511330] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3729.533843] alg: No test for adler32 (adler32-zlib) [ 3730.611403] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 3730.858222] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [ 3732.543182] Key type lgssc registered [ 3733.553441] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3805.851656] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3811.398372] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3834.403344] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3903.926292] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3908.476741] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3926.266826] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3937.689527] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 3965.940799] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8d364aebc000.ost_server_uuid 50 [ 3967.268712] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8d364aebc000.ost_server_uuid in FULL state after 0 sec [ 3967.672921] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 3971.242795] Lustre: Unmounted lustre-client [ 3972.753584] Lustre: Unmounted lustre-client [ 4026.441920] Key type lgssc unregistered [ 4026.655854] LNet: 93981:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4026.661168] LNetError: 93981:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4026.679049] LNet: Removed LNI 192.168.206.44@tcp [ 4027.263489] Key type .llcrypt unregistered [ 4027.266678] Key type ._llcrypt unregistered [ 4058.293783] Key type ._llcrypt registered [ 4058.296152] Key type .llcrypt registered [ 4059.476104] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 07:57:34 (1787831854) [ 4078.080985] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4078.187178] alg: No test for adler32 (adler32-zlib) [ 4079.330653] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 4079.582202] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [ 4081.239194] Key type lgssc registered [ 4082.314213] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4157.376444] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4161.265798] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4181.326289] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4183.864480] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 4204.527967] Lustre: lustre-OST0000-osc-ffff8d36452c9000: Connection to lustre-OST0000 (at 192.168.206.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4205.535163] Lustre: 95343:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787831985/real 1787831985] req@000000005d57762f x1874677596165376/t0(0) o400->lustre-OST0000-osc-ffff8d36452c9000@192.168.206.144@tcp:28/4 lens 224/224 e 0 to 1 dl 1787832001 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4210.722974] Lustre: 95343:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787831990/real 1787831990] req@00000000d2809ff7 x1874677596165888/t0(0) o400->lustre-OST0000-osc-ffff8d36452c9000@192.168.206.144@tcp:28/4 lens 224/224 e 0 to 1 dl 1787832006 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4212.860802] Lustre: lustre-OST0000-osc-ffff8d36452c9000: Connection restored to 192.168.206.144@tcp (at 192.168.206.144@tcp) [ 4214.783598] Lustre: lustre-MDT0000-mdc-ffff8d36452c9000: Connection to lustre-MDT0000 (at 192.168.206.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4214.867823] Lustre: 95343:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787831995/real 1787831995] req@00000000a401cb01 x1874677596166400/t0(0) o400->lustre-OST0000-osc-ffff8d36452c9000@192.168.206.144@tcp:28/4 lens 224/224 e 0 to 1 dl 1787832011 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4229.983153] Lustre: 95345:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787832010/real 1787832010] req@000000003e7f5a4e x1874677596168320/t0(0) o400->MGC192.168.206.144@tcp@192.168.206.144@tcp:26/25 lens 224/224 e 0 to 1 dl 1787832026 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4230.008213] LustreError: MGC192.168.206.144@tcp: Connection to MGS (at 192.168.206.144@tcp) was lost; in progress operations using this service will fail [ 4237.046306] Lustre: lustre-MDT0000-mdc-ffff8d36452c9000: Connection restored to 192.168.206.144@tcp (at 192.168.206.144@tcp) [ 4240.945986] Lustre: Evicted from MGS (at 192.168.206.144@tcp) after server handle changed from 0xa93854f53d6e7aae to 0xa93854f53d6e7edd [ 4240.968807] Lustre: MGC192.168.206.144@tcp: Connection restored to 192.168.206.144@tcp (at 192.168.206.144@tcp) [ 4241.764147] Lustre: Unmounted lustre-client [ 4266.732858] Key type lgssc unregistered [ 4266.959938] LNet: 99658:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4266.968467] LNetError: 99658:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4266.998288] LNet: Removed LNI 192.168.206.44@tcp [ 4267.662358] Key type .llcrypt unregistered [ 4267.664385] Key type ._llcrypt unregistered [ 4280.198608] Key type ._llcrypt registered [ 4280.202874] Key type .llcrypt registered [ 4281.353508] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 08:01:16 (1787832076) [ 4295.377450] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4295.391341] alg: No test for adler32 (adler32-zlib) [ 4296.361638] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 4296.581223] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [ 4298.216135] Key type lgssc registered [ 4299.357142] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4335.287938] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4422.022509] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4427.262418] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4447.383725] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4450.004715] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 4451.704693] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 4453.644617] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 08:04:08 (1787832248) [ 4682.017285] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4692.410253] Lustre: Unmounted lustre-client [ 4820.971639] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4826.620024] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4851.168328] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4898.680806] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 08:11:33 (1787832693) [ 4983.598589] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4989.004512] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5010.929042] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5011.632695] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 5015.760831] Lustre: Unmounted lustre-client [ 5047.617759] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 08:14:02 (1787832842) [ 5134.356387] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5138.604362] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5159.059993] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5159.684582] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 5164.203081] Lustre: Unmounted lustre-client [ 5190.506956] Key type lgssc unregistered [ 5190.740770] LNet: 122664:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5190.750421] LNetError: 122664:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5190.762661] LNet: Removed LNI 192.168.206.44@tcp [ 5191.393326] Key type .llcrypt unregistered [ 5191.395900] Key type ._llcrypt unregistered [ 5204.679564] Key type ._llcrypt registered [ 5204.681270] Key type .llcrypt registered [ 5205.860102] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 08:16:40 (1787833000) [ 5208.206588] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5208.271354] alg: No test for adler32 (adler32-zlib) [ 5209.568868] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 5209.828250] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [ 5211.535248] Key type lgssc registered [ 5212.862823] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5260.194507] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5265.808435] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5285.403980] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5287.039832] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 5300.730534] Lustre: Unmounted lustre-client [ 5330.126643] Key type lgssc unregistered [ 5330.432219] LNet: 126259:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5330.441464] LNetError: 126259:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5330.460819] LNet: Removed LNI 192.168.206.44@tcp [ 5331.386680] Key type .llcrypt unregistered [ 5331.389841] Key type ._llcrypt unregistered [ 5347.044259] Key type ._llcrypt registered [ 5347.046883] Key type .llcrypt registered [ 5348.332465] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 08:19:03 (1787833143) [ 5350.406380] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5350.577720] alg: No test for adler32 (adler32-zlib) [ 5351.700406] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 5352.107604] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [ 5353.871908] Key type lgssc registered [ 5355.583436] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5398.723520] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5403.754706] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5421.991109] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5424.856529] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 5430.254119] Lustre: lustre-OST0000-osc-ffff8d3649a7d800: Connection to lustre-OST0000 (at 192.168.206.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5442.597338] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff8d3649a7d800.ost_server_uuid 50 [ 5443.949319] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 5455.381877] Lustre: Unmounted lustre-client [ 5474.783904] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 08:21:09 (1787833269) [ 5505.182370] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5510.898334] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5532.249702] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5553.402217] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5553.840863] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 5559.281143] Lustre: lustre-OST0000-osc-ffff8d364af1c800: Connection to lustre-OST0000 (at 192.168.206.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5583.716649] Lustre: Unmounted lustre-client [ 5625.319928] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 08:23:40 (1787833420) [ 5655.436120] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5660.801392] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5681.256907] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5698.561792] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5699.126290] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 5704.182460] Lustre: lustre-OST0000-osc-ffff8d3645f1e000: Connection to lustre-OST0000 (at 192.168.206.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5727.754497] Lustre: Unmounted lustre-client [ 5778.477321] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 08:26:13 (1787833573) [ 5861.966914] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5867.687592] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5890.271758] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5955.693917] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5961.607133] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5984.119700] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6014.027388] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 6057.647411] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6060.425844] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 1 sec [ 6063.324953] Lustre: Unmounted lustre-client [ 6100.793981] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 08:31:35 (1787833895) [ 6130.952906] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6135.913480] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6156.104099] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6192.026613] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6221.557932] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 6263.640828] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6265.015359] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6267.888610] Lustre: lustre-OST0001-osc-ffff8d3644f81000: Connection to lustre-OST0001 (at 192.168.206.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6276.988816] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6287.355338] Lustre: Unmounted lustre-client [ 6338.345450] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 08:35:32 (1787834132) [ 6368.189440] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6373.917908] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6393.322321] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6393.902150] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 6414.742708] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6416.298749] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6430.777989] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8d3651e7f800.ost_server_uuid 50 [ 6432.092348] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8d3651e7f800.ost_server_uuid in FULL state after 0 sec [ 6435.728506] Lustre: Unmounted lustre-client [ 6436.103705] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 6436.106913] Lustre: Skipped 3 previous similar messages [ 6436.193877] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 6437.118726] LustreError: 149673:0:(sec.c:403:import_sec_validate_get()) import 00000000756a2192 (NEW) with no sec [ 6491.227962] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 08:38:06 (1787834286) [ 6527.288780] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6533.871821] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6556.766172] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6574.584119] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6574.873148] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 6574.970238] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 6587.796157] Lustre: Unmounted lustre-client [ 6587.799894] Lustre: Skipped 1 previous similar message [ 6619.458335] Key type lgssc unregistered [ 6619.690061] LNet: 153094:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6619.697593] LNetError: 153094:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6619.713673] LNet: Removed LNI 192.168.206.44@tcp [ 6620.468446] Key type .llcrypt unregistered [ 6620.473498] Key type ._llcrypt unregistered [ 6634.319169] Key type ._llcrypt registered [ 6634.320969] Key type .llcrypt registered [ 6635.752898] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 08:40:30 (1787834430) [ 6636.838978] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6636.924722] alg: No test for adler32 (adler32-zlib) [ 6637.970752] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 6638.339336] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [ 6640.063260] Key type lgssc registered [ 6641.058618] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6693.043053] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6697.689457] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6715.606523] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6735.323832] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6735.874955] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 6736.771906] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 6741.787513] LustreError: lustre-MDT0001-mdc-ffff8d3650502800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 6741.804921] Lustre: lustre-MDT0001-mdc-ffff8d3650502800: Connection restored to 192.168.206.144@tcp (at 192.168.206.144@tcp) [ 6746.491195] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6747.740878] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6764.560246] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 6773.808017] Lustre: Unmounted lustre-client [ 6802.217156] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 08:43:17 (1787834597) [ 6888.168833] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6893.486884] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6913.205086] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6913.774690] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 6938.962688] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6940.554038] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6943.232664] Lustre: lustre-OST0001-osc-ffff8d3646545800: Connection to lustre-OST0001 (at 192.168.206.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6952.952374] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6963.312826] Lustre: Unmounted lustre-client [ 7015.804264] Key type lgssc unregistered [ 7016.069154] LNet: 162705:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7016.099213] LNetError: 162705:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7016.123801] LNet: Removed LNI 192.168.206.44@tcp [ 7017.042614] Key type .llcrypt unregistered [ 7017.047224] Key type ._llcrypt unregistered [ 7048.588642] Key type ._llcrypt registered [ 7048.592729] Key type .llcrypt registered [ 7049.980987] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 08:47:24 (1787834844) [ 7052.113546] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7052.131589] alg: No test for adler32 (adler32-zlib) [ 7053.168134] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 7053.379303] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [ 7055.111313] Key type lgssc registered [ 7056.051239] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7098.523048] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7102.901176] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7119.649951] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7122.132572] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 7166.118330] Lustre: Unmounted lustre-client [ 7202.634725] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7203.259937] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 7239.459429] Lustre: Unmounted lustre-client [ 7265.547842] Key type lgssc unregistered [ 7265.752606] LNet: 167151:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7265.761833] LNetError: 167151:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7265.777429] LNet: Removed LNI 192.168.206.44@tcp [ 7266.403633] Key type .llcrypt unregistered [ 7266.406410] Key type ._llcrypt unregistered [ 7281.996069] Key type ._llcrypt registered [ 7281.997711] Key type .llcrypt registered [ 7283.316801] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 08:51:17 (1787835077) [ 7285.190121] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7285.200259] alg: No test for adler32 (adler32-zlib) [ 7286.303664] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 7286.621446] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [ 7288.375477] Key type lgssc registered [ 7289.508413] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7340.223232] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7345.695920] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7373.657413] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7376.550126] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 7395.830652] Lustre: Unmounted lustre-client [ 7421.932451] Key type lgssc unregistered [ 7422.258708] LNet: 170906:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7422.268743] LNetError: 170906:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7422.289180] LNet: Removed LNI 192.168.206.44@tcp [ 7422.988693] Key type .llcrypt unregistered [ 7422.993188] Key type ._llcrypt unregistered [ 7433.806337] Key type ._llcrypt registered [ 7433.809398] Key type .llcrypt registered [ 7434.180745] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7434.201909] alg: No test for adler32 (adler32-zlib) [ 7435.228446] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 7435.417139] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [ 7437.071244] Key type lgssc registered [ 7438.112139] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7478.796581] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7483.226887] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7501.115785] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7506.991671] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 7514.059860] Lustre: Unmounted lustre-client [ 7546.825285] Key type lgssc unregistered [ 7547.158115] LNet: 173969:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7547.172904] LNetError: 173969:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7547.195291] LNet: Removed LNI 192.168.206.44@tcp [ 7548.172773] Key type .llcrypt unregistered [ 7548.176903] Key type ._llcrypt unregistered [ 7566.845164] Key type ._llcrypt registered [ 7566.849646] Key type .llcrypt registered [ 7568.639031] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 08:56:02 (1787835362) [ 7572.302109] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7572.327736] alg: No test for adler32 (adler32-zlib) [ 7573.454619] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 7573.832169] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [ 7575.608106] Key type lgssc registered [ 7577.223832] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7627.283598] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7635.101424] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7655.512963] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7658.589444] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 7675.744159] Lustre: Unmounted lustre-client [ 7708.659588] Key type lgssc unregistered [ 7708.854741] LNet: 177726:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7708.859774] LNetError: 177726:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7708.879467] LNet: Removed LNI 192.168.206.44@tcp [ 7709.422319] Key type .llcrypt unregistered [ 7709.427706] Key type ._llcrypt unregistered [ 7719.780220] Key type ._llcrypt registered [ 7719.782848] Key type .llcrypt registered [ 7720.091780] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7720.114784] alg: No test for adler32 (adler32-zlib) [ 7721.211744] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 7721.381095] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [ 7723.023260] Key type lgssc registered [ 7724.375315] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7766.802916] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7772.405792] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7794.131915] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7796.947964] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 7804.147763] Lustre: Unmounted lustre-client [ 7828.007899] Key type lgssc unregistered [ 7828.315149] LNet: 180790:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7828.328601] LNetError: 180790:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7828.344358] LNet: Removed LNI 192.168.206.44@tcp [ 7829.093664] Key type .llcrypt unregistered [ 7829.095285] Key type ._llcrypt unregistered [ 7846.731128] Key type ._llcrypt registered [ 7846.732388] Key type .llcrypt registered [ 7848.260429] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 09:00:42 (1787835642) [ 7871.661306] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7871.679882] alg: No test for adler32 (adler32-zlib) [ 7872.861422] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 7873.181945] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [ 7874.911129] Key type lgssc registered [ 7876.144497] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7960.817227] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7965.457458] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7988.450206] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8030.450801] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 09:03:45 (1787835825) [ 8066.805226] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8071.975925] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8091.023285] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8093.750111] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 8101.820343] Lustre: Unmounted lustre-client [ 8128.007814] Key type lgssc unregistered [ 8128.365735] LNet: 187909:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8128.371471] LNetError: 187909:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8128.399362] LNet: Removed LNI 192.168.206.44@tcp [ 8129.191196] Key type .llcrypt unregistered [ 8129.196309] Key type ._llcrypt unregistered [ 8144.409174] Key type ._llcrypt registered [ 8144.414136] Key type .llcrypt registered [ 8145.704475] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 8146.959605] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 09:05:42 (1787835942) [ 8163.379461] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8163.515633] alg: No test for adler32 (adler32-zlib) [ 8164.618851] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 8164.960179] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [ 8166.671291] Key type lgssc registered [ 8167.857813] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8264.774367] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8271.925667] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8295.231329] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8315.301580] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8318.068796] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 8343.701335] Lustre: Unmounted lustre-client [ 8475.165844] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8479.716220] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8499.666418] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8536.345450] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 09:12:11 (1787836331) [ 8537.484618] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 8539.139057] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 09:12:13 (1787836333) [ 8635.433822] Key type lgssc unregistered [ 8635.715615] LNet: 199360:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8635.727484] LNetError: 199360:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8635.757859] LNet: Removed LNI 192.168.206.44@tcp [ 8636.558601] Key type .llcrypt unregistered [ 8636.562475] Key type ._llcrypt unregistered [ 8654.322360] Key type ._llcrypt registered [ 8654.327500] Key type .llcrypt registered [ 8655.917076] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 09:14:10 (1787836450) [ 8699.264205] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8699.285083] alg: No test for adler32 (adler32-zlib) [ 8700.381514] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 8700.613500] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [ 8702.359177] Key type lgssc registered [ 8703.471596] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8749.138419] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8754.948799] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8786.729633] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8809.165790] Key type lgssc unregistered [ 8809.426661] LNet: 203095:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8809.434589] LNetError: 203095:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8809.448614] LNet: Removed LNI 192.168.206.44@tcp [ 8810.076770] Key type .llcrypt unregistered [ 8810.079134] Key type ._llcrypt unregistered [ 8825.321896] Key type ._llcrypt registered [ 8825.323726] Key type .llcrypt registered [ 8826.518131] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 09:17:01 (1787836621) [ 8828.935968] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8828.969961] alg: No test for adler32 (adler32-zlib) [ 8830.029822] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 8830.259709] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [ 8831.911341] Key type lgssc registered [ 8833.085842] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8877.935668] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8883.495156] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8904.386415] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8907.031896] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 8917.482247] Lustre: lustre-MDT0000-mdc-ffff8d3643836000: Connection to lustre-MDT0000 (at 192.168.206.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8922.603152] Lustre: lustre-MDT0001-mdc-ffff8d3643836000: Connection to lustre-MDT0001 (at 192.168.206.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8937.952313] Lustre: 204083:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787836718/real 1787836718] req@0000000075077d18 x1874682578026624/t0(0) o400->MGC192.168.206.144@tcp@192.168.206.144@tcp:26/25 lens 224/224 e 0 to 1 dl 1787836734 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 8937.975300] LustreError: MGC192.168.206.144@tcp: Connection to MGS (at 192.168.206.144@tcp) was lost; in progress operations using this service will fail [ 8948.283411] Lustre: Evicted from MGS (at 192.168.206.144@tcp) after server handle changed from 0x7797a65c040024e2 to 0x7797a65c040041e5 [ 8948.297695] Lustre: MGC192.168.206.144@tcp: Connection restored to 192.168.206.144@tcp (at 192.168.206.144@tcp) [ 8968.190205] LustreError: lustre-MDT0000-mdc-ffff8d3643836000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 8968.227028] Lustre: lustre-MDT0000-mdc-ffff8d3643836000: Connection restored to 192.168.206.144@tcp (at 192.168.206.144@tcp) [ 8972.141867] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8973.576029] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 8979.127791] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8980.621664] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 8983.503279] Lustre: Unmounted lustre-client [ 9010.777534] Key type lgssc unregistered [ 9011.163919] LNet: 208043:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9011.172686] LNetError: 208043:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9011.186621] LNet: Removed LNI 192.168.206.44@tcp [ 9012.030501] Key type .llcrypt unregistered [ 9012.033085] Key type ._llcrypt unregistered [ 9031.664445] Key type ._llcrypt registered [ 9031.670135] Key type .llcrypt registered [ 9033.734608] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 09:20:27 (1787836827) [ 9036.735397] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9036.760560] alg: No test for adler32 (adler32-zlib) [ 9037.809644] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 9037.988694] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [ 9039.633335] Key type lgssc registered [ 9041.020462] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9092.905634] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9098.892644] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9156.113846] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9161.797309] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9199.614451] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9222.173975] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9271.382497] Key type lgssc unregistered [ 9271.667878] LNet: 213347:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9271.678391] LNetError: 213347:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9271.716777] LNet: Removed LNI 192.168.206.44@tcp [ 9272.564717] Key type .llcrypt unregistered [ 9272.569497] Key type ._llcrypt unregistered [ 9314.935746] Key type ._llcrypt registered [ 9314.939289] Key type .llcrypt registered [ 9316.355131] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 09:25:10 (1787837110) [ 9350.583441] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9350.592408] alg: No test for adler32 (adler32-zlib) [ 9351.830662] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 9352.168873] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [ 9353.871220] Key type lgssc registered [ 9355.053368] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9433.408404] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9438.560986] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9462.525440] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9500.198540] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 09:28:15 (1787837295) [ 9506.449663] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 09:28:21 (1787837301) [ 9539.726638] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9545.497419] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9565.053583] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9567.871871] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 9572.584103] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [ 9574.103661] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 9575.593464] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 9577.189806] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [ 9583.080854] Lustre: lustre-MDT0000-mdc-ffff8d364aebf000: Connection to lustre-MDT0000 (at 192.168.206.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9598.442289] LustreError: MGC192.168.206.144@tcp: Connection to MGS (at 192.168.206.144@tcp) was lost; in progress operations using this service will fail [ 9598.464871] Lustre: Evicted from MGS (at 192.168.206.144@tcp) after server handle changed from 0x3b1852d538eb1a97 to 0x3b1852d538eb1c0a [ 9598.475903] Lustre: MGC192.168.206.144@tcp: Connection restored to 192.168.206.144@tcp (at 192.168.206.144@tcp) [ 9605.619199] Lustre: 221237:0:(mgc_request.c:1900:mgc_process_log()) MGC192.168.206.144@tcp: IR log lustre-cliir failed, not fatal: rc = -2 [ 9611.572527] Lustre: lustre-MDT0000-mdc-ffff8d364aebf000: Connection restored to 192.168.206.144@tcp (at 192.168.206.144@tcp) [ 9619.767679] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9621.414477] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9624.725823] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [ 9629.165690] Lustre: lustre-MDT0000-mdc-ffff8d364aebf000: Connection to lustre-MDT0000 (at 192.168.206.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9645.535210] Lustre: 215245:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787837425/real 1787837425] req@00000000cd41490d x1874683125319936/t0(0) o400->MGC192.168.206.144@tcp@192.168.206.144@tcp:26/25 lens 224/224 e 0 to 1 dl 1787837441 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9645.559989] LustreError: MGC192.168.206.144@tcp: Connection to MGS (at 192.168.206.144@tcp) was lost; in progress operations using this service will fail [ 9652.001787] Lustre: lustre-MDT0000-mdc-ffff8d364aebf000: Connection restored to 192.168.206.144@tcp (at 192.168.206.144@tcp) [ 9655.092492] Lustre: Evicted from MGS (at 192.168.206.144@tcp) after server handle changed from 0x3b1852d538eb1c0a to 0x3b1852d538eb21ba [ 9655.112407] Lustre: MGC192.168.206.144@tcp: Connection restored to 192.168.206.144@tcp (at 192.168.206.144@tcp) [ 9659.109143] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9660.521840] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9664.350530] Lustre: Unmounted lustre-client [ 9726.629048] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9731.579511] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9750.454812] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9756.200823] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 9757.681666] Lustre: Unmounted lustre-client [ 9782.891895] Key type lgssc unregistered [ 9783.103703] LNet: 225826:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9783.115829] LNetError: 225826:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9783.133665] LNet: Removed LNI 192.168.206.44@tcp [ 9783.760691] Key type .llcrypt unregistered [ 9783.763743] Key type ._llcrypt unregistered [ 9799.548170] Key type ._llcrypt registered [ 9799.550239] Key type .llcrypt registered [ 9800.720672] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 09:33:15 (1787837595) [ 9804.051821] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9804.065315] alg: No test for adler32 (adler32-zlib) [ 9805.066423] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [ 9805.282835] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [ 9806.967510] Key type lgssc registered [ 9807.992187] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9854.434172] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9859.326210] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9878.146697] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9880.745453] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 9886.239383] Lustre: Unmounted lustre-client [ 9949.909643] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9955.505145] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9975.965593] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9976.558526] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [ 9986.009161] Lustre: Unmounted lustre-client [10042.276621] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10047.696612] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10066.903894] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10067.562652] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [10069.347286] Lustre: Unmounted lustre-client [10094.710362] Key type lgssc unregistered [10094.953709] LNet: 232866:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10094.968120] LNetError: 232866:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10094.987126] LNet: Removed LNI 192.168.206.44@tcp [10095.615319] Key type .llcrypt unregistered [10095.618861] Key type ._llcrypt unregistered [10110.149508] Key type ._llcrypt registered [10110.151348] Key type .llcrypt registered [10111.319602] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 09:38:26 (1787837906) [10113.963146] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10114.107204] alg: No test for adler32 (adler32-zlib) [10115.101787] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [10115.354682] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [10117.032126] Key type lgssc registered [10118.196558] Lustre: Echo OBD driver; http://www.lustre.org/ [10155.257976] Key type lgssc unregistered [10155.522815] LNet: 235187:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10155.532684] LNetError: 235187:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10155.546273] LNet: Removed LNI 192.168.206.44@tcp [10156.092363] Key type .llcrypt unregistered [10156.097862] Key type ._llcrypt unregistered [10184.262150] Key type ._llcrypt registered [10184.272676] Key type .llcrypt registered [10184.849373] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10184.868124] alg: No test for adler32 (adler32-zlib) [10185.979308] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [10186.202521] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [10187.871524] Key type lgssc registered [10188.837448] Lustre: Echo OBD driver; http://www.lustre.org/ [10268.711594] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10275.616568] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10296.466634] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10332.830655] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [10334.511524] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 09:42:09 (1787838129) [10363.691569] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10368.957542] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10388.697413] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10413.221814] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10415.157367] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [10420.208715] Lustre: lustre-OST0001-osc-ffff8d364af18800: Connection to lustre-OST0001 (at 192.168.206.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [10427.650910] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [10438.003364] Lustre: Unmounted lustre-client [10471.243496] Key type lgssc unregistered [10471.543549] LNet: 242300:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10471.557408] LNetError: 242300:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10471.581186] LNet: Removed LNI 192.168.206.44@tcp [10472.395688] Key type .llcrypt unregistered [10472.401962] Key type ._llcrypt unregistered [10504.170818] Key type ._llcrypt registered [10504.174424] Key type .llcrypt registered [10505.215990] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 09:45:00 (1787838300) [10519.633378] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10519.684778] alg: No test for adler32 (adler32-zlib) [10520.967977] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [10521.379709] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [10523.119198] Key type lgssc registered [10524.146191] Lustre: Echo OBD driver; http://www.lustre.org/ [10570.959145] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10576.933801] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10597.370126] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 09:46:32 (1787838392) [10628.148914] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10633.315430] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10655.865448] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10657.424590] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [10665.685828] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [10674.717700] Lustre: Unmounted lustre-client [10773.161971] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10778.133815] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10828.834949] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10829.487915] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [10831.959144] Lustre: Unmounted lustre-client [10858.344862] Key type lgssc unregistered [10858.596375] LNet: 250009:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10858.608032] LNetError: 250009:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10858.625307] LNet: Removed LNI 192.168.206.44@tcp [10859.358167] Key type .llcrypt unregistered [10859.360353] Key type ._llcrypt unregistered [10888.538938] Key type ._llcrypt registered [10888.567191] Key type .llcrypt registered [10888.838253] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10888.851383] alg: No test for adler32 (adler32-zlib) [10890.049791] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [10890.365866] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [10892.127299] Key type lgssc registered [10893.420543] Lustre: Echo OBD driver; http://www.lustre.org/ [10981.073611] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10986.821417] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11009.331574] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11057.908054] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [11059.291829] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 09:54:14 (1787838854) [11091.067735] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11096.666057] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11117.203651] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11121.424082] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [11125.339764] Lustre: Unmounted lustre-client [11153.866410] Key type lgssc unregistered [11154.189633] LNet: 256655:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11154.198958] LNetError: 256655:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11154.230546] LNet: Removed LNI 192.168.206.44@tcp [11155.057805] Key type .llcrypt unregistered [11155.061279] Key type ._llcrypt unregistered [11171.862623] Key type ._llcrypt registered [11171.868139] Key type .llcrypt registered [11173.442298] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [11175.619490] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 09:56:10 (1787838970) [11185.448094] Key type .llcrypt unregistered [11185.449543] Key type ._llcrypt unregistered [11195.586436] Key type ._llcrypt registered [11195.606159] Key type .llcrypt registered [11196.406427] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11196.434149] alg: No test for adler32 (adler32-zlib) [11197.585768] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [11198.024663] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [11199.719236] Key type lgssc registered [11201.206127] Lustre: Echo OBD driver; http://www.lustre.org/ [11251.819115] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11266.552189] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [11269.675522] Lustre: Unmounted lustre-client [11295.769748] Key type lgssc unregistered [11295.987929] LNet: 260122:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11296.004304] LNetError: 260122:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11296.025439] LNet: Removed LNI 192.168.206.44@tcp [11296.614060] Key type .llcrypt unregistered [11296.619035] Key type ._llcrypt unregistered [11310.359190] Key type ._llcrypt registered [11310.361881] Key type .llcrypt registered [11311.808294] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 09:58:26 (1787839106) [11314.063260] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11314.107677] alg: No test for adler32 (adler32-zlib) [11315.101607] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [11315.316358] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [11316.999807] Key type lgssc registered [11318.104460] Lustre: Echo OBD driver; http://www.lustre.org/ [11398.362390] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11425.618631] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11430.929968] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11433.589054] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [11436.032499] Lustre: Unmounted lustre-client [11462.682182] Key type lgssc unregistered [11462.910860] LNet: 263666:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11462.919438] LNetError: 263666:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11462.934137] LNet: Removed LNI 192.168.206.44@tcp [11463.451513] Key type .llcrypt unregistered [11463.458416] Key type ._llcrypt unregistered [11475.266622] Key type ._llcrypt registered [11475.268503] Key type .llcrypt registered [11476.431738] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 10:01:11 (1787839271) [11478.444628] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11478.479876] alg: No test for adler32 (adler32-zlib) [11479.504327] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [11479.735865] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [11481.375322] Key type lgssc registered [11482.391670] Lustre: Echo OBD driver; http://www.lustre.org/ [11525.397655] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11530.872958] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11552.262694] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11553.996979] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [11559.412487] Lustre: lustre-MDT0000-mdc-ffff8d3649b71000: Connection to lustre-MDT0000 (at 192.168.206.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [11563.990963] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11564.006298] LustreError: 266831:0:(file.c:6184:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [11564.430262] LustreError: MGC192.168.206.144@tcp: Connection to MGS (at 192.168.206.144@tcp) was lost; in progress operations using this service will fail [11564.490043] Lustre: Unmounted lustre-client [11584.633796] Key type lgssc unregistered [11584.872709] LNet: 267246:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11584.881982] LNetError: 267246:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11584.906052] LNet: Removed LNI 192.168.206.44@tcp [11585.543480] Key type .llcrypt unregistered [11585.546256] Key type ._llcrypt unregistered [11601.270507] Key type ._llcrypt registered [11601.273486] Key type .llcrypt registered [11602.333076] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 10:03:17 (1787839397) [11604.516266] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11604.703101] alg: No test for adler32 (adler32-zlib) [11605.775449] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [11606.116954] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [11607.807325] Key type lgssc registered [11608.856679] Lustre: Echo OBD driver; http://www.lustre.org/ [11652.393459] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11656.928982] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11676.850889] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11678.707146] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [11683.815680] Lustre: lustre-MDT0001-mdc-ffff8d36602d4000: Connection to lustre-MDT0001 (at 192.168.206.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [11690.018741] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [11692.882402] Lustre: Unmounted lustre-client [11710.936234] Key type lgssc unregistered [11711.210855] LNet: 270826:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11711.217748] LNetError: 270826:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11711.250104] LNet: Removed LNI 192.168.206.44@tcp [11711.849856] Key type .llcrypt unregistered [11711.854546] Key type ._llcrypt unregistered [11726.114828] Key type ._llcrypt registered [11726.116983] Key type .llcrypt registered [11727.137094] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 10:05:22 (1787839522) [11742.835664] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11742.857569] alg: No test for adler32 (adler32-zlib) [11744.031377] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [11744.256910] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [11745.927738] Key type lgssc registered [11747.272675] Lustre: Echo OBD driver; http://www.lustre.org/ [11850.235668] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11852.800672] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [11890.666167] Lustre: lustre-MDT0001-mdc-ffff8d3649579000: Connection to lustre-MDT0001 (at 192.168.206.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [11899.083620] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11909.419981] Lustre: Unmounted lustre-client [11942.038171] Key type lgssc unregistered [11942.248644] LNet: 276611:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11942.256377] LNetError: 276611:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11942.270051] LNet: Removed LNI 192.168.206.44@tcp [11942.822967] Key type .llcrypt unregistered [11942.824717] Key type ._llcrypt unregistered [11954.925491] Key type ._llcrypt registered [11954.927783] Key type .llcrypt registered [11956.058675] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 10:09:10 (1787839750) [11957.221621] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [11958.716553] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 10:09:13 (1787839753) [11960.030772] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [11961.337461] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 10:09:16 (1787839756) [11962.747358] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [11963.937174] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 10:09:19 (1787839759) [11965.202084] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [11966.383494] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 10:09:21 (1787839761) [11967.631523] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [11969.361763] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 10:09:24 (1787839764) [11976.495339] Key type .llcrypt unregistered [11976.498854] Key type ._llcrypt unregistered [11983.452495] Key type ._llcrypt registered [11983.459332] Key type .llcrypt registered [11983.693380] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11983.703638] alg: No test for adler32 (adler32-zlib) [11984.750199] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [11984.990032] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [11986.647650] Key type lgssc registered [11987.965260] Lustre: Echo OBD driver; http://www.lustre.org/ [12056.772146] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12061.680200] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12082.621673] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12084.369865] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [12086.524478] Lustre: Unmounted lustre-client [12122.098572] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 10:11:57 (1787839917) [12148.892118] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12153.877154] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12183.558266] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12184.053054] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [12185.295227] Lustre: Unmounted lustre-client [12222.772828] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 10:13:37 (1787840017) [12235.871976] LNet: 1 local NIs in recovery (showing 1): 192.168.206.44@tcp [12858.893657] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12864.697379] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12894.342536] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12999.399897] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 10:26:34 (1787840794) [13008.353968] Key type lgssc unregistered [13008.576902] LNet: 290138:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13008.591334] LNetError: 290138:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13009.640704] LNet: Removed LNI 192.168.206.44@tcp [13010.094747] Key type .llcrypt unregistered [13010.097151] Key type ._llcrypt unregistered [13148.932581] Key type ._llcrypt registered [13148.937851] Key type .llcrypt registered [13149.257401] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13149.279402] alg: No test for adler32 (adler32-zlib) [13150.458606] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [13150.701504] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [13152.367185] Key type lgssc registered [13153.341157] Lustre: Echo OBD driver; http://www.lustre.org/ [13239.001854] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13243.751833] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13267.194619] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13300.070141] Key type lgssc unregistered [13300.438718] LNet: 293838:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13300.453344] LNetError: 293838:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13300.468995] LNet: Removed LNI 192.168.206.44@tcp [13301.408793] Key type .llcrypt unregistered [13301.413851] Key type ._llcrypt unregistered [13340.475845] Key type ._llcrypt registered [13340.478544] Key type .llcrypt registered [13341.748147] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 10:32:16 (1787841136) [13360.490588] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13360.505118] alg: No test for adler32 (adler32-zlib) [13361.607224] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [13361.913975] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [13363.575186] Key type lgssc registered [13364.854468] Lustre: Echo OBD driver; http://www.lustre.org/ [13412.657583] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 10:33:27 (1787841207) [13424.469530] Key type lgssc unregistered [13424.674670] LNet: 297394:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13424.683274] LNetError: 297394:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13424.701512] LNet: Removed LNI 192.168.206.44@tcp [13425.223879] Key type .llcrypt unregistered [13425.227730] Key type ._llcrypt unregistered [13438.389532] Key type ._llcrypt registered [13438.391237] Key type .llcrypt registered [13438.757131] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13438.775462] alg: No test for adler32 (adler32-zlib) [13439.881886] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [13440.337769] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [13442.119168] Key type lgssc registered [13443.454558] Lustre: Echo OBD driver; http://www.lustre.org/ [13499.217201] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13505.083679] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13529.982322] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13801.615633] LNet: Added LNI 192.168.206.44@tcp50 [8/256/0/180] [13803.590720] LNet: Removed LNI 192.168.206.44@tcp [13805.617518] Lustre: 301787:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff8d3647f9c000: config has no valid NIDs, force dynamic NIDs [13806.783175] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [13806.959825] Lustre: Unmounted lustre-client [13810.074506] Key type lgssc unregistered [13810.468294] LNet: 302047:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13810.476277] LNetError: 302047:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13810.496915] LNet: Removed LNI 192.168.206.44@tcp50 [13811.210069] Key type .llcrypt unregistered [13811.214540] Key type ._llcrypt unregistered [13829.127543] Key type ._llcrypt registered [13829.147347] Key type .llcrypt registered [13829.734915] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13829.753549] alg: No test for adler32 (adler32-zlib) [13830.831970] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [13831.041765] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [13832.711302] Key type lgssc registered [13833.815490] Lustre: Echo OBD driver; http://www.lustre.org/ [13895.409255] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13900.846190] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13907.755634] LNet: Added LNI 192.168.206.44@tcp50 [8/256/0/180] [13908.432631] LNet: Removed LNI 192.168.206.44@tcp [13909.649993] Lustre: 304742:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff8d3647f9e800: config has no valid NIDs, force dynamic NIDs [13910.803739] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [13911.436610] Lustre: Unmounted lustre-client [13951.832427] Key type lgssc unregistered [13952.222481] LNet: 305615:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13952.235557] LNetError: 305615:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13952.257247] LNet: Removed LNI 192.168.206.44@tcp50 [13952.868803] Key type .llcrypt unregistered [13952.873544] Key type ._llcrypt unregistered [13966.223883] Key type ._llcrypt registered [13966.232157] Key type .llcrypt registered [13967.754349] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 10:42:42 (1787841762) [13988.231373] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13988.250108] alg: No test for adler32 (adler32-zlib) [13989.412189] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [13989.699735] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [13991.399218] Key type lgssc registered [13992.652770] Lustre: Echo OBD driver; http://www.lustre.org/ [14080.523553] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14087.188823] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14113.502311] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14161.288672] Key type lgssc unregistered [14161.521070] LNet: 310382:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14161.531610] LNetError: 310382:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14161.562570] LNet: Removed LNI 192.168.206.44@tcp [14162.265841] Key type .llcrypt unregistered [14162.269872] Key type ._llcrypt unregistered [14174.713543] Key type ._llcrypt registered [14174.715411] Key type .llcrypt registered [14176.270064] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 10:46:10 (1787841970) [14178.734173] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14178.751918] alg: No test for adler32 (adler32-zlib) [14179.830656] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [14180.175479] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [14181.823235] Key type lgssc registered [14183.846430] Lustre: Echo OBD driver; http://www.lustre.org/ [14235.996252] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14240.936893] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14262.718251] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14265.478746] Lustre: Mounted lustre-client - version 2.17.57_65_g89f8e4a [14321.554623] Lustre: Mounted fs15246-client - version 2.17.57_65_g89f8e4a [14334.232210] Lustre: Unmounted fs15246-client [14357.908764] Lustre: Unmounted lustre-client [14383.631685] Key type lgssc unregistered [14383.882293] LNet: 315246:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14383.890627] LNetError: 315246:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14384.933961] LNet: Removed LNI 192.168.206.44@tcp [14385.607631] Key type .llcrypt unregistered [14385.610427] Key type ._llcrypt unregistered [14395.727810] Key type ._llcrypt registered [14395.731647] Key type .llcrypt registered [14396.960847] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 10:49:51 (1787842191) [14398.431181] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14398.509224] alg: No test for adler32 (adler32-zlib) [14399.529479] Lustre: Lustre: Build Version: 2.17.57_65_g89f8e4a [14399.751749] LNet: Added LNI 192.168.206.44@tcp [8/256/0/180] [14401.407217] Key type lgssc registered [14402.468507] Lustre: Echo OBD driver; http://www.lustre.org/ [14518.998947] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14524.195729] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14546.661584] Lustre: DEBUG MARKER: oleg644-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14641.128348] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 14363 sec ========= 10:53:54 (1787842434) [14643.744926] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 10:53:57 (1787842437) === [14647.809968] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 10:54:02 (1787842442) === [14682.297161] Key type lgssc unregistered [14682.650528] LNet: 324507:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14682.661082] LNetError: 324507:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14682.697717] LNet: Removed LNI 192.168.206.44@tcp [14683.372586] Key type .llcrypt unregistered [14683.375218] Key type ._llcrypt unregistered