[ 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 833260653 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.001013] APIC: Switch to symmetric I/O mode setup [ 0.002325] x2apic enabled [ 0.003011] Switched APIC routing to physical x2apic. [ 0.004017] kvm-guest: setup PV IPIs [ 0.007398] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008019] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009008] pid_max: default: 32768 minimum: 301 [ 0.011042] LSM: Security Framework initializing [ 0.012066] Yama: becoming mindful. [ 0.013032] SELinux: Initializing. [ 0.015036] *** VALIDATE selinux *** [ 0.023776] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.028685] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.030244] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.032098] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.033160] *** VALIDATE tmpfs *** [ 0.035629] *** VALIDATE proc *** [ 0.037382] *** VALIDATE cgroup *** [ 0.038012] *** VALIDATE cgroup2 *** [ 0.039369] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.041053] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.042012] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.044066] Spectre V2 : User space: Vulnerable [ 0.045005] Speculative Store Bypass: Vulnerable [ 0.048000] debug: unmapping init [mem 0xffffffffa6e59000-0xffffffffa6e60fff] [ 0.050000] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.050771] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.051020] ... version: 2 [ 0.052008] ... bit width: 48 [ 0.053008] ... generic registers: 4 [ 0.054009] ... value mask: 0000ffffffffffff [ 0.055010] ... max period: 00007fffffffffff [ 0.056008] ... fixed-purpose events: 3 [ 0.057017] ... event mask: 000000070000000f [ 0.058360] rcu: Hierarchical SRCU implementation. [ 0.061000] smp: Bringing up secondary CPUs ... [ 0.062840] x86: Booting SMP configuration: [ 0.063024] .... node #0, CPUs: #1 #2 #3 [ 0.071718] smp: Brought up 1 node, 4 CPUs [ 0.072988] smpboot: Max logical packages: 1 [ 0.073008] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.532036] node 0 deferred pages initialised in 457ms [ 0.538125] devtmpfs: initialized [ 0.541659] x86/mm: Memory block size: 128MB [ 0.547456] gcov: version magic: 0x41383552 [ 0.552506] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.553124] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.556382] pinctrl core: initialized pinctrl subsystem [ 0.558330] [ 0.558908] ************************************************************* [ 0.562012] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.564009] ** ** [ 0.567011] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.570024] ** ** [ 0.573016] ** This means that this kernel is built to expose internal ** [ 0.576012] ** IOMMU data structures, which may compromise security on ** [ 0.579012] ** your system. ** [ 0.582010] ** ** [ 0.585029] ** If you see this message and you are not debugging the ** [ 0.589013] ** kernel, report this immediately to your vendor! ** [ 0.592014] ** ** [ 0.595011] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.597027] ************************************************************* [ 0.600634] NET: Registered protocol family 16 [ 0.603689] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.607059] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.610170] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.614111] cpuidle: using governor menu [ 0.617109] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.619596] PCI: Using configuration type 1 for base access [ 0.622125] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.630138] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.632043] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.635085] cryptd: max_cpu_qlen set to 1000 [ 0.636562] ACPI: Added _OSI(Module Device) [ 0.638010] ACPI: Added _OSI(Processor Device) [ 0.639009] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.641023] ACPI: Added _OSI(Processor Aggregator Device) [ 0.646944] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.653707] ACPI: Interpreter enabled [ 0.655074] ACPI: PM: (supports S0 S3 S4 S5) [ 0.656013] ACPI: Using IOAPIC for interrupt routing [ 0.658158] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.662430] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.675231] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.677033] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.681015] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.684104] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.691125] acpiphp: Slot [2] registered [ 0.692095] acpiphp: Slot [5] registered [ 0.694095] acpiphp: Slot [6] registered [ 0.696119] acpiphp: Slot [3] registered [ 0.698095] acpiphp: Slot [4] registered [ 0.700085] acpiphp: Slot [7] registered [ 0.701279] acpiphp: Slot [8] registered [ 0.703123] acpiphp: Slot [9] registered [ 0.705078] acpiphp: Slot [10] registered [ 0.707125] acpiphp: Slot [11] registered [ 0.709092] acpiphp: Slot [12] registered [ 0.710135] acpiphp: Slot [13] registered [ 0.712098] acpiphp: Slot [14] registered [ 0.714180] acpiphp: Slot [15] registered [ 0.716095] acpiphp: Slot [16] registered [ 0.717145] acpiphp: Slot [17] registered [ 0.719231] acpiphp: Slot [18] registered [ 0.721249] acpiphp: Slot [19] registered [ 0.724119] acpiphp: Slot [20] registered [ 0.726103] acpiphp: Slot [21] registered [ 0.728187] acpiphp: Slot [22] registered [ 0.730239] acpiphp: Slot [23] registered [ 0.733119] acpiphp: Slot [24] registered [ 0.735101] acpiphp: Slot [25] registered [ 0.737106] acpiphp: Slot [26] registered [ 0.738199] acpiphp: Slot [27] registered [ 0.740125] acpiphp: Slot [28] registered [ 0.742084] acpiphp: Slot [29] registered [ 0.744177] acpiphp: Slot [30] registered [ 0.746127] acpiphp: Slot [31] registered [ 0.747182] PCI host bridge to bus 0000:00 [ 0.749021] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.752018] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.755016] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.758018] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.761149] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.765031] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.767373] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.771472] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.775866] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.791013] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.796048] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.800022] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.803013] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.805014] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.809855] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.812803] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.816051] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.819875] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.824036] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.833751] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.838770] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.847192] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.853013] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.861013] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.876032] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.886969] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.893076] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.900019] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.910018] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.917201] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.919279] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.921186] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.923269] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.924254] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.929092] iommu: Default domain type: Passthrough [ 0.930232] SCSI subsystem initialized [ 0.931000] ACPI: bus type USB registered [ 0.932076] usbcore: registered new interface driver usbfs [ 0.934048] usbcore: registered new interface driver hub [ 0.935049] usbcore: registered new device driver usb [ 0.936107] pps_core: LinuxPPS API ver. 1 registered [ 0.937006] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.939049] PTP clock support registered [ 0.941031] EDAC MC: Ver: 3.0.0 [ 0.942318] PCI: Using ACPI for IRQ routing [ 0.943427] NetLabel: Initializing [ 0.944016] NetLabel: domain hash size = 128 [ 0.945066] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.947086] NetLabel: unlabeled traffic allowed by default [ 0.949003] vgaarb: loaded [ 0.950283] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.951005] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.955968] clocksource: Switched to clocksource kvm-clock [ 1.106625] VFS: Disk quotas dquot_6.6.0 [ 1.108605] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 1.112641] *** VALIDATE ramfs *** [ 1.113819] *** VALIDATE hugetlbfs *** [ 1.115393] pnp: PnP ACPI init [ 1.117852] pnp: PnP ACPI: found 6 devices [ 1.142596] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 1.146836] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 1.150059] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 1.152714] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 1.155568] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 1.158755] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 1.162226] NET: Registered protocol family 2 [ 1.165672] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 1.172963] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 1.176848] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 1.183081] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 1.186784] TCP: Hash tables configured (established 65536 bind 65536) [ 1.190276] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 1.193839] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 1.196799] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 1.200616] NET: Registered protocol family 1 [ 1.203212] RPC: Registered named UNIX socket transport module. [ 1.206214] RPC: Registered udp transport module. [ 1.207872] RPC: Registered tcp transport module. [ 1.209267] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 1.212234] NET: Registered protocol family 44 [ 1.213746] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 1.216303] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 1.218765] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 1.221843] PCI: CLS 0 bytes, default 64 [ 1.223910] Unpacking initramfs... [ 4.241514] debug: unmapping init [mem 0xffff8f73fcc64000-0xffff8f73fffcffff] [ 4.246501] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 4.248627] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 4.251431] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 6.007493] Initialise system trusted keyrings [ 6.010627] Key type blacklist registered [ 6.015410] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 6.026612] zbud: loaded [ 6.029752] *** VALIDATE nfs *** [ 6.032697] *** VALIDATE nfs4 *** [ 6.036314] pstore: using deflate compression [ 6.053647] Platform Keyring initialized [ 6.576356] NET: Registered protocol family 38 [ 6.579906] Key type asymmetric registered [ 6.583293] Asymmetric key parser 'x509' registered [ 6.589046] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 6.599488] io scheduler mq-deadline registered [ 6.601922] io scheduler kyber registered [ 6.606953] io scheduler bfq registered [ 6.617620] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 6.624062] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 6.631978] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 6.638773] ACPI: Power Button [PWRF] [ 6.653061] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 6.668777] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 6.693867] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 6.738172] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 6.774796] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 6.792655] Non-volatile memory driver v1.3 [ 6.796709] Linux agpgart interface v0.103 [ 6.871858] virtio_blk virtio1: [vda] 146680 512-byte logical blocks (75.1 MB/71.6 MiB) [ 6.879183] vda: detected capacity change from 0 to 75100160 [ 6.923994] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 6.942191] vdb: detected capacity change from 0 to 1073741824 [ 6.970841] libphy: Fixed MDIO Bus: probed [ 6.990795] usbcore: registered new interface driver usbserial_generic [ 6.992638] usbserial: USB Serial support registered for generic [ 6.996908] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 7.012920] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 7.017328] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 7.024778] mousedev: PS/2 mouse device common for all mice [ 7.031260] rtc_cmos 00:05: RTC can wake from S4 [ 7.032901] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 7.037834] rtc_cmos 00:05: registered as rtc0 [ 7.037901] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 7.037934] intel_pstate: CPU model not supported [ 7.049532] hid: raw HID events driver (C) Jiri Kosina [ 7.059069] usbcore: registered new interface driver usbhid [ 7.063321] usbhid: USB HID core driver [ 7.067647] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 7.068480] drop_monitor: Initializing network drop monitor service [ 7.068616] Initializing XFRM netlink socket [ 7.068972] NET: Registered protocol family 10 [ 7.073493] Segment Routing with IPv6 [ 7.073533] NET: Registered protocol family 17 [ 7.074320] mpls_gso: MPLS GSO support [ 7.085326] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 7.101672] RAS: Correctable Errors collector initialized. [ 7.106334] AVX version of gcm_enc/dec engaged. [ 7.110306] AES CTR mode by8 optimization enabled [ 7.370741] sched_clock: Marking stable (7370622081, 0)->(8673642347, -1303020266) [ 7.374505] registered taskstats version 1 [ 7.376472] Loading compiled-in X.509 certificates [ 7.378759] zswap: loaded using pool lzo/zbud [ 7.415785] Key type big_key registered [ 7.448804] Key type encrypted registered [ 7.451759] ima: No TPM chip found, activating TPM-bypass! [ 7.457770] ima: Allocated hash algorithm: sha1 [ 7.460417] ima: No architecture policies found [ 7.463937] evm: Initialising EVM extended attributes: [ 7.466820] evm: security.selinux [ 7.468782] evm: security.ima [ 7.472779] evm: security.capability [ 7.474917] evm: HMAC attrs: 0x1 [ 7.481711] rtc_cmos 00:05: setting system clock to 2026-08-27 14:21:46 UTC (1787840506) [ 7.502684] debug: unmapping init [mem 0xffffffffa7e03000-0xffffffffa7ffffff] [ 7.510896] debug: unmapping init [mem 0xffffffffa6b82000-0xffffffffa6e58fff] [ 7.522211] Write protecting the kernel read-only data: 28672k [ 7.527178] debug: unmapping init [mem 0xffffffffa5203000-0xffffffffa53fffff] [ 7.531513] debug: unmapping init [mem 0xffffffffa5b14000-0xffffffffa5bfffff] [ 7.595273] 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) [ 7.611351] systemd[1]: Detected virtualization kvm. [ 7.617695] systemd[1]: Detected architecture x86-64. [ 7.625489] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 7.681815] systemd[1]: No hostname configured. [ 7.685181] systemd[1]: Set hostname to . [ 7.687180] random: systemd: uninitialized urandom read (16 bytes read) [ 7.689040] systemd[1]: Initializing machine ID from random generator. [ 7.807760] random: ln: uninitialized urandom read (6 bytes read) [ 8.011924] random: systemd: uninitialized urandom read (16 bytes read) [ 8.021485] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 8.040290] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 8.048072] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Timers. [ OK ] Listening on Journal Socket. Starting Create list of required st…ce nodes for the current kernel... Starting Apply Kernel Variables... Starting Journal Service... Starting Create Volatile Files and Directories... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Slices. [ OK ] Reached target Paths. Starting Setup Virtual Console... [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. [ OK ] Started Memstrack Anylazing Service. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Setup Virtual Console. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 9.912772] device-mapper: uevent: version 1.0.3 [ 9.918850] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [[ 11.994088] random: fast init done  OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 12.358767] virtio_net virtio0 ens2: renamed from eth0 [ 12.734254] scsi host0: ata_piix [ 12.768831] scsi host1: ata_piix [ 12.773171] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 12.784707] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 18.023439] random: crng init done [ 18.027473] random: 7 urandom warning(s) missed due to ratelimiting [ 19.562844] dracut-initqueue[582]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 22.112109] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Swap. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Apply Kernel Variables. Stopping udev Kernel Device Manager... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 26.358440] printk: systemd: 26 output lines suppressed due to ratelimiting [ 27.586470] SELinux: Disabled at runtime. [ 27.791793] 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) [ 27.804239] systemd[1]: Detected virtualization kvm. [ 27.814381] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 29.320565] hrtimer: interrupt took 6008210 ns [ 29.384370] systemd[1]: initrd-switch-root.service: Succeeded. [ 29.389270] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 29.426342] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 29.435209] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 29.442359] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 29.459777] systemd[1]: Starting Journal Service... Starting Journal Service... [ 29.481966] systemd[1]: Listening on initctl Compatibility Named Pipe. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Reached target rpc_pipefs.target. Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on udev Control Socket. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Created slice system-getty.slice. [[ 29.571054] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS  OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Created slice system-sshd\x2dkeygen.slice. Starting Apply Kernel Variables... Mounting POSIX Message Queue File System... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. Mounting Huge Pages File System... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Stopped target Initrd Root File System. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. Starting Remount Root and Kernel File Systems... Mounting Kernel Debug File System... [ OK ] Created slice system-serial\x2dgetty.slice. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on Process Core Dump Socket. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ OK ] Started Journal Service. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Mounted Huge Pages File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 32.449888] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 34.640306] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 34.644871] input: PC Speaker as /devices/platform/pcspkr/input/input5 [* ] A start job is running for Configur…-only root support (9s / no limit)[ 39.210437] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 39.663798] EDAC sbridge: Ver: 1.1.2 [** ] A start job is running for Configur…only root support (10s / no limit) [*** ] A start job is running for Configur…only root support (11s / no limit) [ *** ] A start job is running for Configur…only root support (11s / no limit) [ *** ] A start job is running for Configur…only root support (12s / no limit) [ ***] A start job is running for Configur…only root support (12s / no limit) [ **] A start job is running for Configur…only root support (13s / no limit) [ *] A start job is running for Configur…only root support (14s / no limit) [ **] A start job is running for Configur…only root support (14s / no limit) [ ***] A start job is running for Configur…only root support (15s / no limit) [ *** ] A start job is running for Configur…only root support (15s / no limit) [ *** ] A start job is running for Configur…only root support (16s / no limit) [*** ] A start job is running for Configur…only root support (17s / no limit) [** ] A start job is running for Configur…only root support (17s / no limit)[ 47.104555] Key type dns_resolver registered [* ] A start job is running for Configur…only root support (18s / no limit)[ 47.618926] NFS: Registering the id_resolver key type [ 47.620924] Key type id_resolver registered [ 47.622625] Key type id_legacy registered [** ] A start job is running for Configur…only root support (18s / no limit) [*** ] A start job is running for Configur…only root support (19s / no limit) [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... [ OK ] Started Load/Save Random Seed. [ OK ] Started 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 ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Restore /run/initramfs on shutdown... Starting Login Service... [ OK ] Reached target sshd-keygen.target. [ OK ] Started irqbalance daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... [ OK ] Started OpenSSH server daemon. Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Getty on tty1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Login Service. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg252-client login: [ 144.680433] libcfs: loading out-of-tree module taints kernel. [ 144.893385] Key type ._llcrypt registered [ 144.897308] Key type .llcrypt registered [ 145.853385] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 145.876197] alg: No test for adler32 (adler32-zlib) [ 147.235811] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [ 148.171079] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 150.327586] Key type lgssc registered [ 154.127764] Lustre: Echo OBD driver; http://www.lustre.org/ [ 331.252670] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 337.281927] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 356.812938] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing check_logdir /tmp/testlogs/ [ 356.831372] Lustre: lustre-OST0000-osc-ffff8f7446785800: disconnect after 23s idle [ 363.092959] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing yml_node [ 367.895566] Lustre: DEBUG MARKER: Client: 2.17.57.69 [ 370.478193] Lustre: DEBUG MARKER: MDS: 2.17.57.69 [ 373.107125] Lustre: DEBUG MARKER: OSS: 2.17.57.69 [ 374.612511] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Thu Aug 27 10:27:52 EDT 2026 [ 393.794611] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 395.460580] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 398.842686] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 409.203237] Lustre: Unmounted lustre-client [ 556.187900] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 564.691884] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 604.010205] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 669.219968] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 10:32:46 (1787841166) [ 672.286268] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 674.376736] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 10:32:51 (1787841171) [ 712.756035] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 718.679296] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 744.424115] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 745.233334] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 750.380721] Lustre: Unmounted lustre-client [ 780.773200] Key type lgssc unregistered [ 781.157399] LNet: 15094:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 781.167684] LNetError: 15094:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 781.178692] LNet: Removed LNI 192.168.202.52@tcp [ 782.083171] Key type .llcrypt unregistered [ 782.086175] Key type ._llcrypt unregistered [ 801.873772] Key type ._llcrypt registered [ 801.879498] Key type .llcrypt registered [ 803.632268] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 10:35:00 (1787841300) [ 805.359815] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 805.541517] alg: No test for adler32 (adler32-zlib) [ 806.765773] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [ 807.233949] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 809.007212] Key type lgssc registered [ 810.950160] Lustre: Echo OBD driver; http://www.lustre.org/ [ 900.372319] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 987.180891] Lustre: Unmounted lustre-client [ 1019.391495] Key type lgssc unregistered [ 1019.940758] LNet: 18911:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1019.949467] LNetError: 18911:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1019.980457] LNet: Removed LNI 192.168.202.52@tcp [ 1020.774276] Key type .llcrypt unregistered [ 1020.776834] Key type ._llcrypt unregistered [ 1032.565468] Key type ._llcrypt registered [ 1032.568241] Key type .llcrypt registered [ 1034.039941] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 10:38:51 (1787841531) [ 1037.176713] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1037.218723] alg: No test for adler32 (adler32-zlib) [ 1038.424969] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [ 1038.759443] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 1040.479240] Key type lgssc registered [ 1041.663219] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1102.382291] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1108.207617] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1131.407627] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1138.799035] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 1153.598659] Lustre: Unmounted lustre-client [ 1181.869118] Key type lgssc unregistered [ 1182.265863] LNet: 22520:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1182.275414] LNetError: 22520:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1182.310340] LNet: Removed LNI 192.168.202.52@tcp [ 1183.093572] Key type .llcrypt unregistered [ 1183.097401] Key type ._llcrypt unregistered [ 1196.440344] Key type ._llcrypt registered [ 1196.442503] Key type .llcrypt registered [ 1197.845615] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 10:41:35 (1787841695) [ 1201.022191] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1201.052456] alg: No test for adler32 (adler32-zlib) [ 1202.269873] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [ 1202.609563] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 1204.439240] Key type lgssc registered [ 1205.768100] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1257.985147] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1264.458430] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1291.310669] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1293.186092] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 1300.009274] Lustre: Unmounted lustre-client [ 1330.950943] Key type lgssc unregistered [ 1331.299816] LNet: 25717:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1331.313428] LNetError: 25717:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1331.341508] LNet: Removed LNI 192.168.202.52@tcp [ 1331.980620] Key type .llcrypt unregistered [ 1331.984607] Key type ._llcrypt unregistered [ 1352.231852] Key type ._llcrypt registered [ 1352.234526] Key type .llcrypt registered [ 1353.558320] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 10:44:11 (1787841851) [ 1356.446280] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1356.501565] alg: No test for adler32 (adler32-zlib) [ 1357.614956] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [ 1357.943972] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 1359.655652] Key type lgssc registered [ 1360.929696] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1425.624872] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1432.421664] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1457.708230] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1459.686473] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 1464.818154] Lustre: lustre-MDT0000-mdc-ffff8f7446781800: Connection to lustre-MDT0000 (at 192.168.202.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1480.193476] Lustre: lustre-MDT0001-mdc-ffff8f7446781800: Connection to lustre-MDT0001 (at 192.168.202.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1485.375830] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1491.429385] Lustre: 26678:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787841974/real 1787841974] req@0000000025ca781b x1874688062003072/t0(0) o400->MGC192.168.202.152@tcp@192.168.202.152@tcp:26/25 lens 224/224 e 0 to 1 dl 1787841990 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1491.495510] LustreError: MGC192.168.202.152@tcp: Connection to MGS (at 192.168.202.152@tcp) was lost; in progress operations using this service will fail [ 1495.733552] Lustre: Unmounted lustre-client [ 1512.321676] Key type lgssc unregistered [ 1512.518700] LNet: 29381:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1512.524249] LNetError: 29381:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1512.536259] LNet: Removed LNI 192.168.202.52@tcp [ 1513.200276] Key type .llcrypt unregistered [ 1513.205828] Key type ._llcrypt unregistered [ 1530.198370] Key type ._llcrypt registered [ 1530.202749] Key type .llcrypt registered [ 1531.693758] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 10:47:09 (1787842029) [ 1534.625724] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1534.659984] alg: No test for adler32 (adler32-zlib) [ 1535.919700] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [ 1536.180082] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 1537.855234] Key type lgssc registered [ 1539.101980] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1599.040195] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1605.852933] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1629.924206] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1642.048684] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 1647.107526] Lustre: lustre-OST0000-osc-ffff8f7447717000: Connection to lustre-OST0000 (at 192.168.202.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1655.327773] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1665.727592] Lustre: Unmounted lustre-client [ 1692.357698] Key type lgssc unregistered [ 1692.633549] LNet: 32994:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1692.646937] LNetError: 32994:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1692.671744] LNet: Removed LNI 192.168.202.52@tcp [ 1693.391360] Key type .llcrypt unregistered [ 1693.394114] Key type ._llcrypt unregistered [ 1711.184766] Key type ._llcrypt registered [ 1711.196759] Key type .llcrypt registered [ 1712.672578] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 10:50:09 (1787842209) [ 1716.038443] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1716.122480] alg: No test for adler32 (adler32-zlib) [ 1717.409476] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [ 1717.917097] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 1719.615212] Key type lgssc registered [ 1720.958703] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1778.135414] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1784.874886] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1806.570728] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1809.370805] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 1813.105559] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1817.069447] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1828.652716] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-08-2710h52m06s [ 1831.496203] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1834.994226] Lustre: lustre-MDT0000-mdc-ffff8f74473cf800: Connection to lustre-MDT0000 (at 192.168.202.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1842.882865] LustreError: 36789:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff8f74473cf800: can't stat MDS #0: rc = -19 [ 1844.437696] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1852.411862] LustreError: MGC192.168.202.152@tcp: Connection to MGS (at 192.168.202.152@tcp) was lost; in progress operations using this service will fail [ 1852.447517] Lustre: Evicted from MGS (at 192.168.202.152@tcp) after server handle changed from 0x488a5a9c3467629e to 0x488a5a9c346763e0 [ 1852.479847] Lustre: MGC192.168.202.152@tcp: Connection restored to 192.168.202.152@tcp (at 192.168.202.152@tcp) [ 1858.536644] LustreError: lustre-MDT0000-mdc-ffff8f74473cf800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1858.572158] Lustre: lustre-MDT0000-mdc-ffff8f74473cf800: Connection restored to 192.168.202.152@tcp (at 192.168.202.152@tcp) [ 1862.977329] Lustre: DEBUG MARKER: Wait for df (36789) ... [ 1864.898316] Lustre: DEBUG MARKER: done [ 1867.874227] Lustre: Unmounted lustre-client [ 1904.544670] Key type lgssc unregistered [ 1904.752948] LNet: 37809:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1904.757184] LNetError: 37809:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1905.766255] LNet: Removed LNI 192.168.202.52@tcp [ 1906.383699] Key type .llcrypt unregistered [ 1906.386189] Key type ._llcrypt unregistered [ 1939.282605] Key type ._llcrypt registered [ 1939.286040] Key type .llcrypt registered [ 1940.474460] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 10:53:58 (1787842438) [ 1943.394730] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1943.409840] alg: No test for adler32 (adler32-zlib) [ 1944.606370] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [ 1944.927498] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 1946.737374] Key type lgssc registered [ 1947.899206] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2003.578246] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2009.706616] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2035.643618] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2037.630207] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 2042.254488] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-08-2710h55m39s [ 2044.194940] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 2053.594111] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 2057.048966] Lustre: DEBUG MARKER: Creating a test file and stat it [ 2077.663420] Lustre: 41962:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787842556/real 1787842556] req@00000000c832a9cf x1874688677517824/t0(0) o101->lustre-MDT0000-mdc-ffff8f7447d5e000@192.168.202.152@tcp:12/10 lens 576/1152 e 0 to 1 dl 1787842576 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 2077.684976] Lustre: lustre-MDT0000-mdc-ffff8f7447d5e000: Connection to lustre-MDT0000 (at 192.168.202.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2077.723986] Lustre: lustre-MDT0000-mdc-ffff8f7447d5e000: Connection restored to 192.168.202.152@tcp (at 192.168.202.152@tcp) [ 2098.869996] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 2102.240468] Lustre: DEBUG MARKER: done [ 2107.265065] Lustre: Unmounted lustre-client [ 2140.399954] Key type lgssc unregistered [ 2140.604816] LNet: 42842:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2140.616809] LNetError: 42842:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2140.628436] LNet: Removed LNI 192.168.202.52@tcp [ 2141.125556] Key type .llcrypt unregistered [ 2141.127186] Key type ._llcrypt unregistered [ 2171.339787] Key type ._llcrypt registered [ 2171.342495] Key type .llcrypt registered [ 2172.521410] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 10:57:50 (1787842670) [ 2173.984859] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 2175.557159] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 10:57:53 (1787842673) [ 2179.675190] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2179.803169] alg: No test for adler32 (adler32-zlib) [ 2180.932317] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [ 2181.105273] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 2182.769162] Key type lgssc registered [ 2184.247084] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2210.830751] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 10:58:28 (1787842708) [ 2248.485124] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2255.012329] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2278.158129] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2280.869106] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 2284.873991] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 2288.646339] Lustre: Unmounted lustre-client [ 2305.995715] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 2358.527622] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2366.393912] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2367.243776] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 2372.928500] Lustre: Unmounted lustre-client [ 2440.813160] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2448.018604] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2448.685778] LustreError: 44257:0:(lmv_obd.c:211:lmv_notify()) activation of lustre-MDT0001_UUID failed: -22 [ 2448.738611] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 2453.809191] Lustre: Unmounted lustre-client [ 2477.984228] Lustre: DEBUG MARKER: files compared the same [ 2502.763556] Key type lgssc unregistered [ 2503.024743] LNet: 50478:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2503.034978] LNetError: 50478:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2503.061052] LNet: Removed LNI 192.168.202.52@tcp [ 2503.842372] Key type .llcrypt unregistered [ 2503.844306] Key type ._llcrypt unregistered [ 2522.073398] Key type ._llcrypt registered [ 2522.080031] Key type .llcrypt registered [ 2523.470255] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 11:03:40 (1787843020) [ 2526.618109] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2526.631584] alg: No test for adler32 (adler32-zlib) [ 2527.865876] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [ 2528.198534] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 2529.933214] Key type lgssc registered [ 2531.712953] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2588.524243] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2596.196853] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2618.896220] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2631.283075] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 2635.690914] Lustre: Unmounted lustre-client [ 2673.260432] Key type lgssc unregistered [ 2673.600799] LNet: 54069:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2673.617499] LNetError: 54069:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2673.645692] LNet: Removed LNI 192.168.202.52@tcp [ 2674.449874] Key type .llcrypt unregistered [ 2674.451421] Key type ._llcrypt unregistered [ 2691.496792] Key type ._llcrypt registered [ 2691.500445] Key type .llcrypt registered [ 2692.820544] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 11:06:30 (1787843190) [ 2695.777336] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2695.877042] alg: No test for adler32 (adler32-zlib) [ 2696.998993] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [ 2697.226773] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 2698.889498] Key type lgssc registered [ 2700.279392] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2787.326805] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2827.237898] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2833.562777] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2862.899338] Key type lgssc unregistered [ 2863.124735] LNet: 57561:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2863.128926] LNetError: 57561:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2863.145156] LNet: Removed LNI 192.168.202.52@tcp [ 2863.703977] Key type .llcrypt unregistered [ 2863.709532] Key type ._llcrypt unregistered [ 2879.560541] Key type ._llcrypt registered [ 2879.562286] Key type .llcrypt registered [ 2881.114321] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 11:09:38 (1787843378) [ 2886.279361] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2886.315199] alg: No test for adler32 (adler32-zlib) [ 2887.734988] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [ 2887.976767] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 2889.639864] Key type lgssc registered [ 2891.470675] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2969.591441] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 2976.539056] Lustre: Unmounted lustre-client [ 3016.377945] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 11:11:53 (1787843513) [ 3140.031090] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3158.006740] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 3166.851769] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3166.859612] LustreError: 64045:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff8f7444e93000: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 3166.902221] Lustre: 58547:0:(llite_lib.c:4342:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.202.152@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 3177.388318] Lustre: Unmounted lustre-client [ 3233.515832] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 11:15:30 (1787843730) [ 3246.984381] Key type lgssc unregistered [ 3247.299289] LNet: 65266:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3247.307810] LNetError: 65266:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3248.362486] LNet: Removed LNI 192.168.202.52@tcp [ 3249.224523] Key type .llcrypt unregistered [ 3249.226756] Key type ._llcrypt unregistered [ 3263.502916] Key type ._llcrypt registered [ 3263.506978] Key type .llcrypt registered [ 3264.283653] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3264.298313] alg: No test for adler32 (adler32-zlib) [ 3265.472874] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [ 3265.842721] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 3267.551948] Key type lgssc registered [ 3269.139692] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3325.649963] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3332.571738] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3357.011611] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3358.898691] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 3363.118964] Lustre: Unmounted lustre-client [ 3395.784639] Key type lgssc unregistered [ 3396.136442] LNet: 68288:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3396.150921] LNetError: 68288:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3396.178690] LNet: Removed LNI 192.168.202.52@tcp [ 3397.091582] Key type .llcrypt unregistered [ 3397.099263] Key type ._llcrypt unregistered [ 3409.554616] Key type ._llcrypt registered [ 3409.585581] Key type .llcrypt registered [ 3409.902781] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3409.928597] alg: No test for adler32 (adler32-zlib) [ 3410.980356] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [ 3411.211751] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 3412.903188] Key type lgssc registered [ 3414.363661] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3532.227695] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3538.982569] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3561.801571] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3563.608790] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 3565.607640] Lustre: Unmounted lustre-client [ 3589.479097] Key type lgssc unregistered [ 3589.777794] LNet: 71727:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3589.784450] LNetError: 71727:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3590.825994] LNet: Removed LNI 192.168.202.52@tcp [ 3591.585383] Key type .llcrypt unregistered [ 3591.587838] Key type ._llcrypt unregistered [ 3608.427184] Key type ._llcrypt registered [ 3608.431222] Key type .llcrypt registered [ 3609.721333] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 11:21:47 (1787844107) [ 3612.677678] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3612.702466] alg: No test for adler32 (adler32-zlib) [ 3613.808647] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [ 3614.093068] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 3615.839271] Key type lgssc registered [ 3616.984692] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3670.710918] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3676.998552] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3700.244271] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3703.088241] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 3709.249191] Lustre: Unmounted lustre-client [ 3709.763573] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 3712.714461] Lustre: Unmounted lustre-client [ 3749.164268] Key type lgssc unregistered [ 3749.394232] LNet: 75424:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3749.401867] LNetError: 75424:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3749.418938] LNet: Removed LNI 192.168.202.52@tcp [ 3749.959832] Key type .llcrypt unregistered [ 3749.962334] Key type ._llcrypt unregistered [ 3764.814813] Key type ._llcrypt registered [ 3764.818889] Key type .llcrypt registered [ 3765.207744] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3765.245066] alg: No test for adler32 (adler32-zlib) [ 3766.411858] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [ 3766.746993] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 3768.506214] Key type lgssc registered [ 3770.374826] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3830.793517] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3840.832807] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3876.747133] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3878.703616] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 3883.505492] Lustre: Unmounted lustre-client [ 3923.383160] Key type lgssc unregistered [ 3923.656206] LNet: 78470:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3923.661117] LNetError: 78470:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3923.676832] LNet: Removed LNI 192.168.202.52@tcp [ 3924.498692] Key type .llcrypt unregistered [ 3924.500537] Key type ._llcrypt unregistered [ 3946.240863] Key type ._llcrypt registered [ 3946.248255] Key type .llcrypt registered [ 3948.161868] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 11:27:25 (1787844445) [ 3951.468861] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3951.497768] alg: No test for adler32 (adler32-zlib) [ 3952.765740] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [ 3953.108626] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 3954.879230] Key type lgssc registered [ 3956.412719] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4012.986253] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4019.451525] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4043.999687] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4045.701328] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 4050.787283] Lustre: lustre: root_squash is set to 0:0 [ 4060.707750] Lustre: lustre: nosquash_nids is cleared [ 4060.774257] Lustre: lustre: root_squash is set to 500:500 [ 4068.185102] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.202.52@tcp 192.168.0.[2,10]@tcp [ 4076.713463] Lustre: lustre: nosquash_nids set to 192.168.202.52/24@tcp [ 4086.957403] Lustre: Unmounted lustre-client [ 4112.849712] Key type lgssc unregistered [ 4113.180871] LNet: 82413:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4113.186443] LNetError: 82413:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4113.205937] LNet: Removed LNI 192.168.202.52@tcp [ 4114.302571] Key type .llcrypt unregistered [ 4114.310046] Key type ._llcrypt unregistered [ 4132.856058] Key type ._llcrypt registered [ 4132.860256] Key type .llcrypt registered [ 4134.336908] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 11:30:31 (1787844631) [ 4136.892065] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 4138.928122] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 11:30:36 (1787844636) [ 4141.852954] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4141.870696] alg: No test for adler32 (adler32-zlib) [ 4142.996840] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [ 4143.337418] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 4145.071217] Key type lgssc registered [ 4146.509124] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4200.094576] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4206.314738] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4232.272774] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4233.049053] Lustre: lustre: root_squash is set to 500:500 [ 4233.086418] Lustre: lustre: nosquash_nids set to 192.168.202.52/24@tcp [ 4234.196792] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 4240.276086] Lustre: Unmounted lustre-client [ 4269.160805] Key type lgssc unregistered [ 4269.471839] LNet: 86380:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4269.480642] LNetError: 86380:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4269.499895] LNet: Removed LNI 192.168.202.52@tcp [ 4270.307402] Key type .llcrypt unregistered [ 4270.315316] Key type ._llcrypt unregistered [ 4288.680404] Key type ._llcrypt registered [ 4288.682826] Key type .llcrypt registered [ 4289.989463] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 4292.292441] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 11:33:09 (1787844789) [ 4313.060407] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4313.078229] alg: No test for adler32 (adler32-zlib) [ 4314.289353] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [ 4314.632757] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 4316.385308] Key type lgssc registered [ 4317.629288] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4408.742308] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4414.612815] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4439.803515] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4518.718193] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4525.524573] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4550.810110] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4567.597944] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 4604.134927] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8f74454f4800.ost_server_uuid 50 [ 4606.423990] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8f74454f4800.ost_server_uuid in IDLE state after 0 sec [ 4607.240675] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 4612.781321] Lustre: Unmounted lustre-client [ 4616.197611] Lustre: Unmounted lustre-client [ 4672.392669] Key type lgssc unregistered [ 4672.660105] LNet: 94062:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4672.665794] LNetError: 94062:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4672.684728] LNet: Removed LNI 192.168.202.52@tcp [ 4673.327881] Key type .llcrypt unregistered [ 4673.329693] Key type ._llcrypt unregistered [ 4708.079603] Key type ._llcrypt registered [ 4708.084302] Key type .llcrypt registered [ 4709.848166] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 11:40:06 (1787845206) [ 4733.626220] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4733.883619] alg: No test for adler32 (adler32-zlib) [ 4734.914373] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [ 4735.161471] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 4736.911426] Key type lgssc registered [ 4738.209807] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4831.015634] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4837.223817] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4861.010429] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4864.047129] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 4885.473883] Lustre: 95426:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787845368/real 1787845368] req@00000000c70f8100 x1874691604092160/t0(0) o400->lustre-OST0000-osc-ffff8f7448fe8800@192.168.202.152@tcp:28/4 lens 224/224 e 0 to 1 dl 1787845384 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4885.506952] Lustre: lustre-OST0000-osc-ffff8f7448fe8800: Connection to lustre-OST0000 (at 192.168.202.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4891.617721] Lustre: 95427:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787845373/real 1787845373] req@00000000021406b3 x1874691604092672/t0(0) o400->lustre-OST0000-osc-ffff8f7448fe8800@192.168.202.152@tcp:28/4 lens 224/224 e 0 to 1 dl 1787845389 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4895.759161] Lustre: 95427:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787845378/real 1787845378] req@00000000028ff9dc x1874691604093184/t0(0) o400->lustre-OST0000-osc-ffff8f7448fe8800@192.168.202.152@tcp:28/4 lens 224/224 e 0 to 1 dl 1787845394 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4895.842918] Lustre: lustre-OST0000-osc-ffff8f7448fe8800: Connection restored to 192.168.202.152@tcp (at 192.168.202.152@tcp) [ 4900.846813] Lustre: lustre-MDT0000-mdc-ffff8f7448fe8800: Connection to lustre-MDT0000 (at 192.168.202.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4901.856258] Lustre: 95427:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787845383/real 1787845383] req@000000007dfd7c87 x1874691604093696/t0(0) o400->lustre-OST0000-osc-ffff8f7448fe8800@192.168.202.152@tcp:28/4 lens 224/224 e 0 to 1 dl 1787845399 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4915.999169] Lustre: 95426:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787845399/real 1787845399] req@000000008e798a64 x1874691604096000/t0(0) o400->MGC192.168.202.152@tcp@192.168.202.152@tcp:26/25 lens 224/224 e 0 to 1 dl 1787845415 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4916.016815] LustreError: MGC192.168.202.152@tcp: Connection to MGS (at 192.168.202.152@tcp) was lost; in progress operations using this service will fail [ 4923.991517] Lustre: lustre-MDT0000-mdc-ffff8f7448fe8800: Connection restored to 192.168.202.152@tcp (at 192.168.202.152@tcp) [ 4926.511379] Lustre: Evicted from MGS (at 192.168.202.152@tcp) after server handle changed from 0x464c14559199b6c9 to 0x464c14559199bae3 [ 4926.542757] Lustre: MGC192.168.202.152@tcp: Connection restored to 192.168.202.152@tcp (at 192.168.202.152@tcp) [ 4930.389430] Lustre: Unmounted lustre-client [ 4958.641970] Key type lgssc unregistered [ 4958.897436] LNet: 99735:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4958.904874] LNetError: 99735:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4958.931711] LNet: Removed LNI 192.168.202.52@tcp [ 4959.750665] Key type .llcrypt unregistered [ 4959.755766] Key type ._llcrypt unregistered [ 4980.528829] Key type ._llcrypt registered [ 4980.535852] Key type .llcrypt registered [ 4982.500048] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 11:44:39 (1787845479) [ 4999.186392] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4999.206711] alg: No test for adler32 (adler32-zlib) [ 5000.379965] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [ 5000.734559] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5002.407567] Key type lgssc registered [ 5004.016798] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5045.392498] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5159.474503] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5166.805145] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5194.527935] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5196.474296] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 5199.047337] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 5201.413798] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 11:48:18 (1787845698) [ 5476.734307] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5487.168085] Lustre: Unmounted lustre-client [ 5626.110040] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5633.341123] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5660.021418] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5705.476633] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 11:56:42 (1787846202) [ 5815.952680] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5824.397427] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5851.870692] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5852.885650] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 5858.331224] Lustre: Unmounted lustre-client [ 5902.860164] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 11:59:59 (1787846399) [ 6012.512930] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6019.614360] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6045.491494] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6046.268565] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 6051.107374] Lustre: Unmounted lustre-client [ 6090.641595] Key type lgssc unregistered [ 6091.085849] LNet: 122802:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6091.105060] LNetError: 122802:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6091.133317] LNet: Removed LNI 192.168.202.52@tcp [ 6091.946524] Key type .llcrypt unregistered [ 6091.951291] Key type ._llcrypt unregistered [ 6111.530489] Key type ._llcrypt registered [ 6111.535076] Key type .llcrypt registered [ 6113.290831] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 12:03:30 (1787846610) [ 6115.909374] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6116.032769] alg: No test for adler32 (adler32-zlib) [ 6117.136319] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [ 6117.442928] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 6119.121042] Key type lgssc registered [ 6120.758591] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6174.586715] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6181.580528] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6206.616078] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6208.570641] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 6223.884565] Lustre: Unmounted lustre-client [ 6253.861860] Key type lgssc unregistered [ 6254.143936] LNet: 126401:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6254.166255] LNetError: 126401:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6254.203136] LNet: Removed LNI 192.168.202.52@tcp [ 6254.855508] Key type .llcrypt unregistered [ 6254.857971] Key type ._llcrypt unregistered [ 6274.356739] Key type ._llcrypt registered [ 6274.358255] Key type .llcrypt registered [ 6276.217350] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 12:06:13 (1787846773) [ 6279.643938] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6279.765264] alg: No test for adler32 (adler32-zlib) [ 6281.022835] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [ 6281.357956] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 6283.183216] Key type lgssc registered [ 6284.399733] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6348.780364] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6356.273520] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6382.318707] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6389.377847] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 6394.362307] Lustre: lustre-OST0000-osc-ffff8f7446783800: Connection to lustre-OST0000 (at 192.168.202.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6409.673503] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff8f7446783800.ost_server_uuid 50 [ 6412.261915] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 6423.902739] Lustre: Unmounted lustre-client [ 6451.234314] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 12:09:08 (1787846948) [ 6486.706571] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6494.656680] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6520.136345] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6545.954574] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6546.610953] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 6552.045986] Lustre: lustre-OST0000-osc-ffff8f74454f1000: Connection to lustre-OST0000 (at 192.168.202.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6581.399144] Lustre: Unmounted lustre-client [ 6643.611914] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 12:12:20 (1787847140) [ 6681.929303] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6689.181635] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6711.752060] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6733.575455] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6734.363578] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 6739.445368] Lustre: lustre-OST0000-osc-ffff8f7448aef800: Connection to lustre-OST0000 (at 192.168.202.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6757.011621] Lustre: Unmounted lustre-client [ 6810.214134] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 12:15:07 (1787847307) [ 6920.013619] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6925.589777] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6950.493119] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7021.501721] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7027.798710] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7049.939157] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7081.371923] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 7130.636451] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7132.357680] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7134.790483] Lustre: Unmounted lustre-client [ 7175.646751] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 12:21:13 (1787847673) [ 7215.106776] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7221.602667] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7245.769080] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7284.419254] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7320.387616] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 7368.848411] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7371.067303] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7384.367399] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7394.752526] Lustre: Unmounted lustre-client [ 7455.659983] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 12:25:53 (1787847953) [ 7495.743481] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7503.787421] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7530.873701] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7531.600785] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 7559.699030] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7562.114652] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7581.280389] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8f7447d5c800.ost_server_uuid 50 [ 7583.253691] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8f7447d5c800.ost_server_uuid in IDLE state after 0 sec [ 7587.996933] Lustre: Unmounted lustre-client [ 7588.338638] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 7588.351264] Lustre: Skipped 3 previous similar messages [ 7588.510539] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 7589.868175] LustreError: 149847:0:(sec.c:403:import_sec_validate_get()) import 000000000c5ba2df (NEW) with no sec [ 7670.885870] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 12:29:28 (1787848168) [ 7711.162779] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7717.693852] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7740.999629] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7766.458934] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7767.148355] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 7767.407188] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 7783.151629] Lustre: Unmounted lustre-client [ 7783.158916] Lustre: Skipped 1 previous similar message [ 7824.798436] Key type lgssc unregistered [ 7825.164399] LNet: 153273:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7825.170954] LNetError: 153273:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7825.192248] LNet: Removed LNI 192.168.202.52@tcp [ 7826.300419] Key type .llcrypt unregistered [ 7826.306427] Key type ._llcrypt unregistered [ 7846.762379] Key type ._llcrypt registered [ 7846.765772] Key type .llcrypt registered [ 7848.862777] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 12:32:25 (1787848345) [ 7850.707984] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7850.718447] alg: No test for adler32 (adler32-zlib) [ 7851.975619] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [ 7852.435386] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 7854.252085] Key type lgssc registered [ 7856.174674] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7922.224708] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7928.911960] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7956.450552] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7986.847092] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7987.628946] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7988.833039] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 7997.485395] LustreError: lustre-MDT0001-mdc-ffff8f7448aea800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 7997.539183] Lustre: lustre-MDT0001-mdc-ffff8f7448aea800: Connection restored to 192.168.202.152@tcp (at 192.168.202.152@tcp) [ 8005.292866] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8007.476290] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 8032.787194] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 8042.839473] Lustre: Unmounted lustre-client [ 8078.239515] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 12:36:15 (1787848575) [ 8199.472420] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8207.188787] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8236.247834] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8237.071457] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 8269.268602] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8271.516685] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 8285.984688] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 8296.358355] Lustre: Unmounted lustre-client [ 8336.095770] Key type lgssc unregistered [ 8336.358077] LNet: 162908:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8336.362324] LNetError: 162908:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8336.392984] LNet: Removed LNI 192.168.202.52@tcp [ 8337.289584] Key type .llcrypt unregistered [ 8337.299874] Key type ._llcrypt unregistered [ 8376.602752] Key type ._llcrypt registered [ 8376.610207] Key type .llcrypt registered [ 8377.938497] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 12:41:15 (1787848875) [ 8380.545165] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8380.665327] alg: No test for adler32 (adler32-zlib) [ 8381.938749] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [ 8382.203503] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 8383.907484] Key type lgssc registered [ 8385.212778] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8446.066742] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8453.444168] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8479.745668] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8482.880360] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 8534.513809] Lustre: Unmounted lustre-client [ 8585.784567] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8586.708865] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 8624.649382] Lustre: Unmounted lustre-client [ 8656.482484] Key type lgssc unregistered [ 8656.790958] LNet: 167362:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8656.805783] LNetError: 167362:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8656.845207] LNet: Removed LNI 192.168.202.52@tcp [ 8657.694357] Key type .llcrypt unregistered [ 8657.696030] Key type ._llcrypt unregistered [ 8678.869353] Key type ._llcrypt registered [ 8678.871460] Key type .llcrypt registered [ 8680.204802] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 12:46:17 (1787849177) [ 8683.243067] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8683.378017] alg: No test for adler32 (adler32-zlib) [ 8684.588334] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [ 8684.975839] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 8686.647472] Key type lgssc registered [ 8688.029507] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8751.802985] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8758.585669] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8788.765905] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8791.511789] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 8815.676389] Lustre: Unmounted lustre-client [ 8857.215901] Key type lgssc unregistered [ 8857.515663] LNet: 171122:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8857.532587] LNetError: 171122:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8857.566482] LNet: Removed LNI 192.168.202.52@tcp [ 8858.295656] Key type .llcrypt unregistered [ 8858.297494] Key type ._llcrypt unregistered [ 8874.010097] Key type ._llcrypt registered [ 8874.013763] Key type .llcrypt registered [ 8874.346780] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8874.356410] alg: No test for adler32 (adler32-zlib) [ 8875.340223] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [ 8875.516471] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 8877.183228] Key type lgssc registered [ 8878.335219] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8942.945812] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8952.340890] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8983.784903] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8985.818347] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 8993.866945] Lustre: Unmounted lustre-client [ 9028.896299] Key type lgssc unregistered [ 9029.459737] LNet: 174193:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9029.477920] LNetError: 174193:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9029.520766] LNet: Removed LNI 192.168.202.52@tcp [ 9030.751615] Key type .llcrypt unregistered [ 9030.754805] Key type ._llcrypt unregistered [ 9055.127672] Key type ._llcrypt registered [ 9055.135464] Key type .llcrypt registered [ 9056.799546] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 12:52:33 (1787849553) [ 9059.959723] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9059.990720] alg: No test for adler32 (adler32-zlib) [ 9061.378579] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [ 9061.728723] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 9063.535192] Key type lgssc registered [ 9064.944164] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9124.268270] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9132.040363] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9158.695919] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9165.935135] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 9192.287804] Lustre: Unmounted lustre-client [ 9223.269375] Key type lgssc unregistered [ 9223.711202] LNet: 177954:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9223.746877] LNetError: 177954:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9223.778804] LNet: Removed LNI 192.168.202.52@tcp [ 9224.645888] Key type .llcrypt unregistered [ 9224.652406] Key type ._llcrypt unregistered [ 9239.285100] Key type ._llcrypt registered [ 9239.294358] Key type .llcrypt registered [ 9239.642557] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9239.661948] alg: No test for adler32 (adler32-zlib) [ 9240.740515] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [ 9241.008483] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 9242.712552] Key type lgssc registered [ 9243.963279] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9304.119986] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9311.362727] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9337.801876] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9344.074700] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 9352.194658] Lustre: Unmounted lustre-client [ 9386.846800] Key type lgssc unregistered [ 9387.140278] LNet: 181024:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9387.152792] LNetError: 181024:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9387.173403] LNet: Removed LNI 192.168.202.52@tcp [ 9387.990464] Key type .llcrypt unregistered [ 9387.997512] Key type ._llcrypt unregistered [ 9408.550454] Key type ._llcrypt registered [ 9408.558934] Key type .llcrypt registered [ 9410.496666] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 12:58:27 (1787849907) [ 9436.353483] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9436.386288] alg: No test for adler32 (adler32-zlib) [ 9437.548367] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [ 9438.010735] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 9439.711926] Key type lgssc registered [ 9441.809427] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9544.374673] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9552.379440] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9579.663200] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9638.039337] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 13:02:15 (1787850135) [ 9677.473360] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9684.116562] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9707.971526] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9709.813660] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [ 9716.824106] Lustre: Unmounted lustre-client [ 9759.008368] Key type lgssc unregistered [ 9759.564854] LNet: 188142:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9759.581715] LNetError: 188142:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9759.614518] LNet: Removed LNI 192.168.202.52@tcp [ 9760.936466] Key type .llcrypt unregistered [ 9760.938251] Key type ._llcrypt unregistered [ 9784.198717] Key type ._llcrypt registered [ 9784.201104] Key type .llcrypt registered [ 9785.864115] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 9788.026704] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 13:04:45 (1787850285) [ 9809.078844] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9809.098171] alg: No test for adler32 (adler32-zlib) [ 9810.267105] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [ 9810.680921] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 9812.455176] Key type lgssc registered [ 9814.068757] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9923.200462] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9929.747877] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9957.842633] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9983.210773] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9984.998097] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [10013.805457] Lustre: Unmounted lustre-client [10167.108717] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10173.316792] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10202.199632] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10258.409619] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 13:12:35 (1787850755) [10259.744854] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [10261.776717] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 13:12:39 (1787850759) [10376.222688] Key type lgssc unregistered [10376.440953] LNet: 199597:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10376.458425] LNetError: 199597:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10376.481908] LNet: Removed LNI 192.168.202.52@tcp [10377.429356] Key type .llcrypt unregistered [10377.435382] Key type ._llcrypt unregistered [10396.829846] Key type ._llcrypt registered [10396.831912] Key type .llcrypt registered [10398.469957] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 13:14:55 (1787850895) [10446.810638] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10446.832422] alg: No test for adler32 (adler32-zlib) [10447.885655] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [10448.032541] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [10449.695710] Key type lgssc registered [10451.019862] Lustre: Echo OBD driver; http://www.lustre.org/ [10510.003575] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10515.706241] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10551.179409] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10581.300477] Key type lgssc unregistered [10581.616909] LNet: 203336:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10581.642440] LNetError: 203336:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10581.671218] LNet: Removed LNI 192.168.202.52@tcp [10582.712697] Key type .llcrypt unregistered [10582.714345] Key type ._llcrypt unregistered [10601.487927] Key type ._llcrypt registered [10601.491471] Key type .llcrypt registered [10603.311509] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 13:18:20 (1787851100) [10606.673108] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10607.015719] alg: No test for adler32 (adler32-zlib) [10608.341734] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [10608.779901] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [10610.527231] Key type lgssc registered [10612.526924] Lustre: Echo OBD driver; http://www.lustre.org/ [10670.532265] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10677.052818] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10703.986614] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10711.653568] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [10721.786991] Lustre: lustre-MDT0000-mdc-ffff8f744416b800: Connection to lustre-MDT0000 (at 192.168.202.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [10732.025882] Lustre: lustre-MDT0001-mdc-ffff8f744416b800: Connection to lustre-MDT0001 (at 192.168.202.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [10742.175173] Lustre: 204326:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787851225/real 1787851225] req@00000000a46314d0 x1874697762455808/t0(0) o400->MGC192.168.202.152@tcp@192.168.202.152@tcp:26/25 lens 224/224 e 0 to 1 dl 1787851241 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10742.212788] LustreError: MGC192.168.202.152@tcp: Connection to MGS (at 192.168.202.152@tcp) was lost; in progress operations using this service will fail [10767.856257] Lustre: Evicted from MGS (at 192.168.202.152@tcp) after server handle changed from 0x637ebdf5a3861171 to 0x637ebdf5a3862cc9 [10767.872375] Lustre: MGC192.168.202.152@tcp: Connection restored to 192.168.202.152@tcp (at 192.168.202.152@tcp) [10778.106354] LustreError: lustre-MDT0000-mdc-ffff8f744416b800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [10778.141969] Lustre: lustre-MDT0000-mdc-ffff8f744416b800: Connection restored to 192.168.202.152@tcp (at 192.168.202.152@tcp) [10784.422621] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10786.324925] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10792.405798] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10793.913427] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [10797.233652] Lustre: Unmounted lustre-client [10834.687718] Key type lgssc unregistered [10835.006400] LNet: 208284:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10835.015272] LNetError: 208284:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10835.031904] LNet: Removed LNI 192.168.202.52@tcp [10835.818718] Key type .llcrypt unregistered [10835.820772] Key type ._llcrypt unregistered [10853.601738] Key type ._llcrypt registered [10853.605078] Key type .llcrypt registered [10855.060929] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 13:22:32 (1787851352) [10858.462794] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10858.484612] alg: No test for adler32 (adler32-zlib) [10859.550867] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [10859.908059] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [10861.575898] Key type lgssc registered [10862.774262] Lustre: Echo OBD driver; http://www.lustre.org/ [10928.216388] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10935.643401] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10996.603526] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11003.879460] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11043.341178] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11071.381309] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11138.935122] Key type lgssc unregistered [11139.283850] LNet: 213590:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11139.290809] LNetError: 213590:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11139.309817] LNet: Removed LNI 192.168.202.52@tcp [11140.140304] Key type .llcrypt unregistered [11140.143237] Key type ._llcrypt unregistered [11194.329521] Key type ._llcrypt registered [11194.331675] Key type .llcrypt registered [11196.693670] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 13:28:13 (1787851693) [11244.099889] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11244.142641] alg: No test for adler32 (adler32-zlib) [11245.313174] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [11245.543411] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [11247.335256] Key type lgssc registered [11249.782028] Lustre: Echo OBD driver; http://www.lustre.org/ [11348.000355] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11354.313415] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11382.886819] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11434.659363] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 13:32:11 (1787851931) [11444.540714] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 13:32:21 (1787851941) [11487.928495] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11494.098776] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11518.363444] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11530.859583] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [11537.656343] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [11540.294996] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [11541.910550] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [11543.770260] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [11546.099254] Lustre: lustre-MDT0000-mdc-ffff8f7448aee000: Connection to lustre-MDT0000 (at 192.168.202.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [11567.586931] Lustre: 215489:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787852050/real 1787852050] req@00000000205c7886 x1874698430327296/t0(0) o400->MGC192.168.202.152@tcp@192.168.202.152@tcp:26/25 lens 224/224 e 0 to 1 dl 1787852066 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [11567.624226] LustreError: MGC192.168.202.152@tcp: Connection to MGS (at 192.168.202.152@tcp) was lost; in progress operations using this service will fail [11567.658423] Lustre: Evicted from MGS (at 192.168.202.152@tcp) after server handle changed from 0x4e0341789971f367 to 0x4e0341789971f4e1 [11567.684050] Lustre: MGC192.168.202.152@tcp: Connection restored to 192.168.202.152@tcp (at 192.168.202.152@tcp) [11578.928766] Lustre: lustre-MDT0000-mdc-ffff8f7448aee000: Connection restored to 192.168.202.152@tcp (at 192.168.202.152@tcp) [11588.904884] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11591.642601] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [11597.176290] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [11603.455502] Lustre: lustre-MDT0000-mdc-ffff8f7448aee000: Connection to lustre-MDT0000 (at 192.168.202.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [11619.743361] Lustre: 215489:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787852102/real 1787852102] req@00000000d61ac76a x1874698430336256/t0(0) o400->MGC192.168.202.152@tcp@192.168.202.152@tcp:26/25 lens 224/224 e 0 to 1 dl 1787852118 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [11619.781569] LustreError: MGC192.168.202.152@tcp: Connection to MGS (at 192.168.202.152@tcp) was lost; in progress operations using this service will fail [11629.045096] Lustre: Evicted from MGS (at 192.168.202.152@tcp) after server handle changed from 0x4e0341789971f4e1 to 0x4e0341789971f7f1 [11629.064881] Lustre: MGC192.168.202.152@tcp: Connection restored to 192.168.202.152@tcp (at 192.168.202.152@tcp) [11635.843573] Lustre: lustre-MDT0000-mdc-ffff8f7448aee000: Connection restored to 192.168.202.152@tcp (at 192.168.202.152@tcp) [11648.716020] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11650.860578] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [11655.901381] Lustre: Unmounted lustre-client [11735.930306] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11743.523897] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11770.689423] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11777.248028] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [11781.056689] Lustre: Unmounted lustre-client [11814.394549] Key type lgssc unregistered [11814.651452] LNet: 226084:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11814.669495] LNetError: 226084:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11814.694174] LNet: Removed LNI 192.168.202.52@tcp [11815.242377] Key type .llcrypt unregistered [11815.246702] Key type ._llcrypt unregistered [11842.504507] Key type ._llcrypt registered [11842.508132] Key type .llcrypt registered [11844.819748] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 13:39:01 (1787852341) [11850.771819] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11850.779781] alg: No test for adler32 (adler32-zlib) [11851.918392] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [11852.210200] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [11853.954137] Key type lgssc registered [11855.269331] Lustre: Echo OBD driver; http://www.lustre.org/ [11914.708494] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11921.624310] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11945.379463] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11946.976638] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [11954.420229] Lustre: Unmounted lustre-client [12024.762063] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12033.175393] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12062.963360] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12063.812667] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [12074.688312] Lustre: Unmounted lustre-client [12163.346351] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12172.027170] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12197.964987] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12198.846397] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [12202.061135] Lustre: Unmounted lustre-client [12236.287467] Key type lgssc unregistered [12236.933411] LNet: 233148:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12236.953739] LNetError: 233148:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12236.995893] LNet: Removed LNI 192.168.202.52@tcp [12238.775351] Key type .llcrypt unregistered [12238.781566] Key type ._llcrypt unregistered [12261.605185] Key type ._llcrypt registered [12261.606768] Key type .llcrypt registered [12262.996239] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 13:46:00 (1787852760) [12266.344611] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12266.361096] alg: No test for adler32 (adler32-zlib) [12267.854776] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [12268.575746] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [12270.503215] Key type lgssc registered [12272.137462] Lustre: Echo OBD driver; http://www.lustre.org/ [12329.682831] Key type lgssc unregistered [12330.114707] LNet: 235476:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12330.128416] LNetError: 235476:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12330.171574] LNet: Removed LNI 192.168.202.52@tcp [12331.564526] Key type .llcrypt unregistered [12331.573460] Key type ._llcrypt unregistered [12368.055494] Key type ._llcrypt registered [12368.059444] Key type .llcrypt registered [12368.595609] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12368.628833] alg: No test for adler32 (adler32-zlib) [12370.132768] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [12370.609670] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [12372.368594] Key type lgssc registered [12373.991482] Lustre: Echo OBD driver; http://www.lustre.org/ [12463.931701] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12470.615296] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12496.874981] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12543.597630] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [12545.687144] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 13:50:42 (1787853042) [12587.400908] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12594.428129] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12619.966786] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12648.569822] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12650.417458] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [12655.601357] Lustre: lustre-OST0001-osc-ffff8f74434e8000: Connection to lustre-OST0001 (at 192.168.202.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [12664.130539] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12674.490931] Lustre: Unmounted lustre-client [12711.669198] Key type lgssc unregistered [12712.036807] LNet: 242597:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12712.048082] LNetError: 242597:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12712.081176] LNet: Removed LNI 192.168.202.52@tcp [12712.886699] Key type .llcrypt unregistered [12712.888189] Key type ._llcrypt unregistered [12751.291533] Key type ._llcrypt registered [12751.293125] Key type .llcrypt registered [12752.931056] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 13:54:10 (1787853250) [12770.164919] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12770.211879] alg: No test for adler32 (adler32-zlib) [12771.404679] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [12771.750557] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [12773.479940] Key type lgssc registered [12774.731925] Lustre: Echo OBD driver; http://www.lustre.org/ [12835.724128] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12843.765252] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12879.388359] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 13:56:16 (1787853376) [12922.573181] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12929.573737] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12954.763947] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12956.952403] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [12972.093032] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [12973.726120] Lustre: Unmounted lustre-client [13086.581751] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13093.259320] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13151.908688] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13152.731133] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [13154.978662] Lustre: Unmounted lustre-client [13189.619778] Key type lgssc unregistered [13189.908727] LNet: 250298:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13189.914377] LNetError: 250298:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13189.946115] LNet: Removed LNI 192.168.202.52@tcp [13190.855812] Key type .llcrypt unregistered [13190.859535] Key type ._llcrypt unregistered [13222.984965] Key type ._llcrypt registered [13222.987225] Key type .llcrypt registered [13223.560947] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13223.575286] alg: No test for adler32 (adler32-zlib) [13224.710383] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [13225.157405] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [13226.943618] Key type lgssc registered [13228.266226] Lustre: Echo OBD driver; http://www.lustre.org/ [13319.101677] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13325.362726] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13350.144693] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13396.086792] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [13398.027311] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 14:04:55 (1787853895) [13438.727246] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13445.382753] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13468.384048] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13477.961602] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [13481.419430] Lustre: Unmounted lustre-client [13510.015983] Key type lgssc unregistered [13510.325654] LNet: 256938:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13510.328793] LNetError: 256938:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13510.344816] LNet: Removed LNI 192.168.202.52@tcp [13511.168112] Key type .llcrypt unregistered [13511.170828] Key type ._llcrypt unregistered [13528.962467] Key type ._llcrypt registered [13528.965986] Key type .llcrypt registered [13530.482577] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [13532.250571] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 14:07:09 (1787854029) [13543.256499] Key type .llcrypt unregistered [13543.261398] Key type ._llcrypt unregistered [13553.513984] Key type ._llcrypt registered [13553.517472] Key type .llcrypt registered [13554.010873] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13554.021520] alg: No test for adler32 (adler32-zlib) [13555.145618] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [13555.340667] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [13557.009145] Key type lgssc registered [13558.109529] Lustre: Echo OBD driver; http://www.lustre.org/ [13616.686265] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13636.111056] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [13639.026546] Lustre: Unmounted lustre-client [13666.922224] Key type lgssc unregistered [13667.136388] LNet: 260406:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13667.146475] LNetError: 260406:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13667.168267] LNet: Removed LNI 192.168.202.52@tcp [13667.868575] Key type .llcrypt unregistered [13667.870400] Key type ._llcrypt unregistered [13685.863253] Key type ._llcrypt registered [13685.865415] Key type .llcrypt registered [13687.708857] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 14:09:44 (1787854184) [13690.190536] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13690.236931] alg: No test for adler32 (adler32-zlib) [13691.266826] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [13691.483251] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [13693.119438] Key type lgssc registered [13694.541721] Lustre: Echo OBD driver; http://www.lustre.org/ [13779.476878] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13815.393319] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13822.545314] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13825.071735] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [13828.276140] Lustre: Unmounted lustre-client [13855.451638] Key type lgssc unregistered [13855.782054] LNet: 263958:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13855.789824] LNetError: 263958:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13855.824114] LNet: Removed LNI 192.168.202.52@tcp [13856.489781] Key type .llcrypt unregistered [13856.491837] Key type ._llcrypt unregistered [13873.121357] Key type ._llcrypt registered [13873.122853] Key type .llcrypt registered [13874.671456] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 14:12:52 (1787854372) [13877.604043] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13877.698206] alg: No test for adler32 (adler32-zlib) [13878.916820] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [13879.227621] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [13880.968873] Key type lgssc registered [13882.124614] Lustre: Echo OBD driver; http://www.lustre.org/ [13935.614698] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13940.772829] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13962.298929] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13964.970050] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [13970.416708] Lustre: lustre-MDT0000-mdc-ffff8f74454f6800: Connection to lustre-MDT0000 (at 192.168.202.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [13975.275988] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [13975.304538] LustreError: 267112:0:(file.c:6184:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [13975.587184] LustreError: MGC192.168.202.152@tcp: Connection to MGS (at 192.168.202.152@tcp) was lost; in progress operations using this service will fail [13975.610480] Lustre: Unmounted lustre-client [13995.017112] Key type lgssc unregistered [13995.273096] LNet: 267529:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13995.288134] LNetError: 267529:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13995.304640] LNet: Removed LNI 192.168.202.52@tcp [13995.998676] Key type .llcrypt unregistered [13996.002247] Key type ._llcrypt unregistered [14012.780564] Key type ._llcrypt registered [14012.784337] Key type .llcrypt registered [14014.247085] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 14:15:11 (1787854511) [14016.433244] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14016.485150] alg: No test for adler32 (adler32-zlib) [14017.780948] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [14018.259532] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [14019.911177] Key type lgssc registered [14020.951275] Lustre: Echo OBD driver; http://www.lustre.org/ [14068.554748] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14074.602940] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14097.282253] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14100.125711] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [14110.921246] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [14113.220043] Lustre: Unmounted lustre-client [14135.782058] Key type lgssc unregistered [14136.052879] LNet: 271111:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14136.064204] LNetError: 271111:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14136.086809] LNet: Removed LNI 192.168.202.52@tcp [14136.822774] Key type .llcrypt unregistered [14136.831285] Key type ._llcrypt unregistered [14153.291820] Key type ._llcrypt registered [14153.293788] Key type .llcrypt registered [14154.706634] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 14:17:32 (1787854652) [14174.871082] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14174.946791] alg: No test for adler32 (adler32-zlib) [14176.075888] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [14176.458053] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [14178.167212] Key type lgssc registered [14179.607522] Lustre: Echo OBD driver; http://www.lustre.org/ [14288.113318] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14291.077111] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [14331.380271] Lustre: lustre-MDT0001-mdc-ffff8f7447bb0000: Connection to lustre-MDT0001 (at 192.168.202.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [14339.638582] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [14349.996790] Lustre: Unmounted lustre-client [14386.390721] Key type lgssc unregistered [14386.609182] LNet: 276899:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14386.617621] LNetError: 276899:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14386.633908] LNet: Removed LNI 192.168.202.52@tcp [14387.349952] Key type .llcrypt unregistered [14387.357679] Key type ._llcrypt unregistered [14405.508490] Key type ._llcrypt registered [14405.510639] Key type .llcrypt registered [14407.069830] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 14:21:44 (1787854904) [14408.961631] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [14411.146198] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 14:21:48 (1787854908) [14413.503866] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [14416.014753] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 14:21:53 (1787854913) [14417.910487] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [14420.232545] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 14:21:57 (1787854917) [14422.785467] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [14424.975402] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 14:22:02 (1787854922) [14427.049930] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [14429.342607] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 14:22:06 (1787854926) [14440.315617] Key type .llcrypt unregistered [14440.318854] Key type ._llcrypt unregistered [14448.378282] Key type ._llcrypt registered [14448.384729] Key type .llcrypt registered [14448.760303] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14448.771414] alg: No test for adler32 (adler32-zlib) [14449.921300] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [14450.215057] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [14451.991327] Key type lgssc registered [14453.322234] Lustre: Echo OBD driver; http://www.lustre.org/ [14534.000048] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14542.577231] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14573.001945] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14574.773621] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [14577.488923] Lustre: Unmounted lustre-client [14606.955109] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 14:25:04 (1787855104) [14643.810892] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14650.272240] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14688.586298] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14689.525693] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [14692.623173] Lustre: Unmounted lustre-client [14747.125957] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 14:27:24 (1787855244) [14751.391561] LNet: 1 local NIs in recovery (showing 1): 192.168.202.52@tcp [15397.329738] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15404.725880] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15445.176731] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15564.616989] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 14:41:02 (1787856062) [15577.671148] Key type lgssc unregistered [15578.046252] LNet: 290447:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15578.055841] LNetError: 290447:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15578.083181] LNet: Removed LNI 192.168.202.52@tcp [15579.257165] Key type .llcrypt unregistered [15579.263257] Key type ._llcrypt unregistered [15693.426732] Key type ._llcrypt registered [15693.432479] Key type .llcrypt registered [15694.534776] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15694.558581] alg: No test for adler32 (adler32-zlib) [15696.039836] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [15696.405840] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [15698.111541] Key type lgssc registered [15700.073487] Lustre: Echo OBD driver; http://www.lustre.org/ [15805.659993] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15812.627330] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15839.933769] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15875.272103] Key type lgssc unregistered [15875.592844] LNet: 294143:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15875.599088] LNetError: 294143:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15875.613986] LNet: Removed LNI 192.168.202.52@tcp [15876.404697] Key type .llcrypt unregistered [15876.406402] Key type ._llcrypt unregistered [15918.272559] Key type ._llcrypt registered [15918.282827] Key type .llcrypt registered [15919.354230] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 14:46:57 (1787856417) [15944.173956] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15944.251595] alg: No test for adler32 (adler32-zlib) [15945.440500] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [15945.758679] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [15947.447890] Key type lgssc registered [15948.897061] Lustre: Echo OBD driver; http://www.lustre.org/ [16007.084774] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 14:48:24 (1787856504) [16021.345740] Key type lgssc unregistered [16021.656739] LNet: 297706:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16021.662894] LNetError: 297706:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16021.670297] LNet: Removed LNI 192.168.202.52@tcp [16022.543744] Key type .llcrypt unregistered [16022.545887] Key type ._llcrypt unregistered [16035.078348] Key type ._llcrypt registered [16035.081905] Key type .llcrypt registered [16035.546727] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [16035.558829] alg: No test for adler32 (adler32-zlib) [16036.827195] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [16037.106873] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [16038.911282] Key type lgssc registered [16039.920263] Lustre: Echo OBD driver; http://www.lustre.org/ [16101.899630] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16108.665812] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16132.694412] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16449.129756] LNet: Added LNI 192.168.202.52@tcp50 [8/256/0/180] [16451.556891] LNet: Removed LNI 192.168.202.52@tcp [16453.837245] Lustre: 302120:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff8f74475d3800: config has no valid NIDs, force dynamic NIDs [16455.178607] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [16455.410981] Lustre: Unmounted lustre-client [16458.961299] Key type lgssc unregistered [16459.567875] LNet: 302381:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16459.578667] LNetError: 302381:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16459.606400] LNet: Removed LNI 192.168.202.52@tcp50 [16460.309374] Key type .llcrypt unregistered [16460.314134] Key type ._llcrypt unregistered [16480.710315] Key type ._llcrypt registered [16480.716070] Key type .llcrypt registered [16481.397388] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [16481.420898] alg: No test for adler32 (adler32-zlib) [16482.505537] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [16482.775931] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [16484.463202] Key type lgssc registered [16485.760541] Lustre: Echo OBD driver; http://www.lustre.org/ [16551.836151] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16559.854353] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16566.924734] LNet: Added LNI 192.168.202.52@tcp50 [8/256/0/180] [16567.956873] LNet: Removed LNI 192.168.202.52@tcp [16569.149070] Lustre: 305073:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff8f7446787800: config has no valid NIDs, force dynamic NIDs [16570.373286] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [16571.129416] Lustre: Unmounted lustre-client [16607.365564] Key type lgssc unregistered [16607.635782] LNet: 305947:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16607.648609] LNetError: 305947:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16607.662508] LNet: Removed LNI 192.168.202.52@tcp50 [16608.082493] Key type .llcrypt unregistered [16608.084389] Key type ._llcrypt unregistered [16620.242934] Key type ._llcrypt registered [16620.246403] Key type .llcrypt registered [16621.693689] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 14:58:39 (1787857119) [16640.407859] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16640.455867] alg: No test for adler32 (adler32-zlib) [16641.492516] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [16641.690061] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [16643.376315] Key type lgssc registered [16644.739412] Lustre: Echo OBD driver; http://www.lustre.org/ [16740.424882] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16746.538565] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16773.590489] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16808.620776] Key type lgssc unregistered [16808.922816] LNet: 310707:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16808.934270] LNetError: 310707:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16808.955369] LNet: Removed LNI 192.168.202.52@tcp [16809.724233] Key type .llcrypt unregistered [16809.728318] Key type ._llcrypt unregistered [16822.555340] Key type ._llcrypt registered [16822.561667] Key type .llcrypt registered [16824.547496] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 15:02:01 (1787857321) [16827.561687] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16827.876485] alg: No test for adler32 (adler32-zlib) [16828.954701] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [16829.225459] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [16830.882692] Key type lgssc registered [16832.097347] Lustre: Echo OBD driver; http://www.lustre.org/ [16888.281250] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16894.476284] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16918.460635] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16920.428394] Lustre: Mounted lustre-client - version 2.17.57_69_gb81e743 [16984.829949] Lustre: Mounted fs15246-client - version 2.17.57_69_gb81e743 [16998.735765] Lustre: Unmounted fs15246-client [17026.293157] Lustre: Unmounted lustre-client [17053.711566] Key type lgssc unregistered [17053.962602] LNet: 315569:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [17053.976336] LNetError: 315569:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [17055.025265] LNet: Removed LNI 192.168.202.52@tcp [17055.819682] Key type .llcrypt unregistered [17055.822116] Key type ._llcrypt unregistered [17067.364515] Key type ._llcrypt registered [17067.368598] Key type .llcrypt registered [17068.721420] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 15:06:06 (1787857566) [17070.129567] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [17070.202223] alg: No test for adler32 (adler32-zlib) [17071.183634] Lustre: Lustre: Build Version: 2.17.57_69_gb81e743 [17071.308656] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [17072.967318] Key type lgssc registered [17074.193359] Lustre: Echo OBD driver; http://www.lustre.org/ [17225.880845] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [17232.994897] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [17258.827801] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [17382.922708] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 17007 sec ========= 15:11:20 (1787857880) [17385.102881] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 15:11:22 (1787857882) === [17389.168327] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 15:11:26 (1787857886) === [17428.371521] Key type lgssc unregistered [17428.684168] LNet: 324847:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [17428.700830] LNetError: 324847:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [17428.737243] LNet: Removed LNI 192.168.202.52@tcp [17429.466915] Key type .llcrypt unregistered [17429.470354] Key type ._llcrypt unregistered