[ 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.16.2-1.fc38 04/01/2014 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 461364888 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 0x000f5b50-0x000f5b5f] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F5970 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE1BB7 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE1A53 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 001A13 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE1AC7 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE1B57 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE1B8F 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe1a53-0xbffe1ac6] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe1a52] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe1ac7-0xbffe1b56] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe1b57-0xbffe1b8e] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe1b8f-0xbffe1bb6] [ 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.001011] APIC: Switch to symmetric I/O mode setup [ 0.002380] x2apic enabled [ 0.003008] Switched APIC routing to physical x2apic. [ 0.004016] kvm-guest: setup PV IPIs [ 0.007000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.007000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.007021] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.008014] pid_max: default: 32768 minimum: 301 [ 0.009133] LSM: Security Framework initializing [ 0.010066] Yama: becoming mindful. [ 0.011048] SELinux: Initializing. [ 0.012088] *** VALIDATE selinux *** [ 0.020400] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.024512] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.026200] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028011] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029230] *** VALIDATE tmpfs *** [ 0.031036] *** VALIDATE proc *** [ 0.032366] *** VALIDATE cgroup *** [ 0.033019] *** VALIDATE cgroup2 *** [ 0.034341] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.035189] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036014] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.037040] Spectre V2 : User space: Vulnerable [ 0.039007] Speculative Store Bypass: Vulnerable [ 0.042882] debug: unmapping init [mem 0xffffffffa2a59000-0xffffffffa2a60fff] [ 0.045191] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.046761] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.047028] ... version: 2 [ 0.048014] ... bit width: 48 [ 0.049011] ... generic registers: 4 [ 0.050015] ... value mask: 0000ffffffffffff [ 0.051020] ... max period: 00007fffffffffff [ 0.052015] ... fixed-purpose events: 3 [ 0.053013] ... event mask: 000000070000000f [ 0.054369] rcu: Hierarchical SRCU implementation. [ 0.056531] smp: Bringing up secondary CPUs ... [ 0.057529] x86: Booting SMP configuration: [ 0.058028] .... node #0, CPUs: #1 #2 #3 [ 0.069678] smp: Brought up 1 node, 4 CPUs [ 0.071017] smpboot: Max logical packages: 1 [ 0.072014] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.201990] node 0 deferred pages initialised in 125ms [ 0.206156] devtmpfs: initialized [ 0.207512] x86/mm: Memory block size: 128MB [ 0.212063] gcov: version magic: 0x41383552 [ 0.215494] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.218121] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.220429] pinctrl core: initialized pinctrl subsystem [ 0.221255] [ 0.222012] ************************************************************* [ 0.225018] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.228024] ** ** [ 0.229015] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.232018] ** ** [ 0.236017] ** This means that this kernel is built to expose internal ** [ 0.239014] ** IOMMU data structures, which may compromise security on ** [ 0.242018] ** your system. ** [ 0.245022] ** ** [ 0.250020] ** If you see this message and you are not debugging the ** [ 0.255022] ** kernel, report this immediately to your vendor! ** [ 0.258015] ** ** [ 0.261018] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.264021] ************************************************************* [ 0.269000] NET: Registered protocol family 16 [ 0.270763] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.272113] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.275103] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.279014] cpuidle: using governor menu [ 0.280777] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.281707] PCI: Using configuration type 1 for base access [ 0.282153] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.295072] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.296028] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.302111] cryptd: max_cpu_qlen set to 1000 [ 0.304264] ACPI: Added _OSI(Module Device) [ 0.306023] ACPI: Added _OSI(Processor Device) [ 0.308017] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.310019] ACPI: Added _OSI(Processor Aggregator Device) [ 0.318233] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.327830] ACPI: Interpreter enabled [ 0.329717] ACPI: PM: (supports S0 S3 S4 S5) [ 0.330025] ACPI: Using IOAPIC for interrupt routing [ 0.331000] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.332474] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.341000] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.341055] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.343033] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.346106] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.351301] acpiphp: Slot [2] registered [ 0.353219] acpiphp: Slot [3] registered [ 0.355159] acpiphp: Slot [4] registered [ 0.357133] acpiphp: Slot [5] registered [ 0.358227] acpiphp: Slot [6] registered [ 0.360231] acpiphp: Slot [7] registered [ 0.362142] acpiphp: Slot [8] registered [ 0.363134] acpiphp: Slot [9] registered [ 0.365130] acpiphp: Slot [10] registered [ 0.367142] acpiphp: Slot [11] registered [ 0.368142] acpiphp: Slot [12] registered [ 0.370128] acpiphp: Slot [13] registered [ 0.371000] acpiphp: Slot [14] registered [ 0.371000] acpiphp: Slot [15] registered [ 0.372200] acpiphp: Slot [16] registered [ 0.374199] acpiphp: Slot [17] registered [ 0.375142] acpiphp: Slot [18] registered [ 0.377163] acpiphp: Slot [19] registered [ 0.379187] acpiphp: Slot [20] registered [ 0.381176] acpiphp: Slot [21] registered [ 0.382131] acpiphp: Slot [22] registered [ 0.384123] acpiphp: Slot [23] registered [ 0.386165] acpiphp: Slot [24] registered [ 0.389522] acpiphp: Slot [25] registered [ 0.395191] acpiphp: Slot [26] registered [ 0.396164] acpiphp: Slot [27] registered [ 0.398136] acpiphp: Slot [28] registered [ 0.399142] acpiphp: Slot [29] registered [ 0.401132] acpiphp: Slot [30] registered [ 0.403176] acpiphp: Slot [31] registered [ 0.405238] PCI host bridge to bus 0000:00 [ 0.406030] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.409034] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.412037] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.415039] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.419036] pci_bus 0000:00: root bus resource [mem 0x180000000-0x1ffffffff window] [ 0.423052] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.425210] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.433947] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.446065] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.455000] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.457000] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.457000] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.460026] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.463026] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.465000] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.468871] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.471054] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.474765] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.480019] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.491684] pci 0000:00:02.0: reg 0x20: [mem 0xfebf4000-0xfebf7fff 64bit pref] [ 0.499021] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.507765] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.530023] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.548025] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.585023] pci 0000:00:05.0: reg 0x20: [mem 0xfebf8000-0xfebfbfff 64bit pref] [ 0.604564] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.622023] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.628023] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.650026] pci 0000:00:06.0: reg 0x20: [mem 0xfebfc000-0xfebfffff 64bit pref] [ 0.669917] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.675589] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.676539] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.678634] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.680414] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.683189] iommu: Default domain type: Passthrough [ 0.690462] SCSI subsystem initialized [ 0.692161] ACPI: bus type USB registered [ 0.693000] usbcore: registered new interface driver usbfs [ 0.693084] usbcore: registered new interface driver hub [ 0.694000] usbcore: registered new device driver usb [ 0.696208] pps_core: LinuxPPS API ver. 1 registered [ 0.697011] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.700065] PTP clock support registered [ 0.702126] EDAC MC: Ver: 3.0.0 [ 0.704000] PCI: Using ACPI for IRQ routing [ 0.704530] NetLabel: Initializing [ 0.705012] NetLabel: domain hash size = 128 [ 0.706000] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.707092] NetLabel: unlabeled traffic allowed by default [ 0.708101] vgaarb: loaded [ 0.709328] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.710014] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.718882] clocksource: Switched to clocksource kvm-clock [ 0.858786] VFS: Disk quotas dquot_6.6.0 [ 0.861316] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.862967] *** VALIDATE ramfs *** [ 0.864284] *** VALIDATE hugetlbfs *** [ 0.865946] pnp: PnP ACPI init [ 0.870472] pnp: PnP ACPI: found 6 devices [ 0.886246] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.889238] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.891317] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.893347] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.895875] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.898269] pci_bus 0000:00: resource 8 [mem 0x180000000-0x1ffffffff window] [ 0.901179] NET: Registered protocol family 2 [ 0.903664] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.908978] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.913673] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.921755] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.928505] TCP: Hash tables configured (established 65536 bind 65536) [ 0.933988] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.937183] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.938885] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.941755] NET: Registered protocol family 1 [ 0.945066] RPC: Registered named UNIX socket transport module. [ 0.950841] RPC: Registered udp transport module. [ 0.952678] RPC: Registered tcp transport module. [ 0.956926] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.963845] NET: Registered protocol family 44 [ 0.968581] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.971089] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.975501] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.980131] PCI: CLS 0 bytes, default 64 [ 0.984695] Unpacking initramfs... [ 3.806471] debug: unmapping init [mem 0xffff9d2b3cc64000-0xffff9d2b3ffcffff] [ 3.826197] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 3.829324] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 3.839222] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 5.127269] Initialise system trusted keyrings [ 5.128751] Key type blacklist registered [ 5.132226] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 5.141980] zbud: loaded [ 5.146369] *** VALIDATE nfs *** [ 5.147381] *** VALIDATE nfs4 *** [ 5.149324] pstore: using deflate compression [ 5.153524] Platform Keyring initialized [ 5.354198] NET: Registered protocol family 38 [ 5.357125] Key type asymmetric registered [ 5.358614] Asymmetric key parser 'x509' registered [ 5.360577] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 5.363952] io scheduler mq-deadline registered [ 5.367393] io scheduler kyber registered [ 5.369350] io scheduler bfq registered [ 5.371215] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 5.375723] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 5.379532] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 5.383709] ACPI: Power Button [PWRF] [ 5.556857] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 5.683206] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 5.791551] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 5.854537] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 5.891931] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 5.899419] Non-volatile memory driver v1.3 [ 5.901191] Linux agpgart interface v0.103 [ 6.024856] virtio_blk virtio1: [vda] 133848 512-byte logical blocks (68.5 MB/65.4 MiB) [ 6.030686] vda: detected capacity change from 0 to 68530176 [ 6.066477] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 6.075681] vdb: detected capacity change from 0 to 1073741824 [ 6.091719] libphy: Fixed MDIO Bus: probed [ 6.106379] usbcore: registered new interface driver usbserial_generic [ 6.108729] usbserial: USB Serial support registered for generic [ 6.119685] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 6.124603] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 6.126734] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 6.140422] mousedev: PS/2 mouse device common for all mice [ 6.148967] rtc_cmos 00:05: RTC can wake from S4 [ 6.156663] rtc_cmos 00:05: registered as rtc0 [ 6.158648] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 6.164299] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 6.171552] intel_pstate: CPU model not supported [ 6.175521] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 6.179326] hid: raw HID events driver (C) Jiri Kosina [ 6.181509] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 6.184826] usbcore: registered new interface driver usbhid [ 6.187172] usbhid: USB HID core driver [ 6.189307] drop_monitor: Initializing network drop monitor service [ 6.191404] Initializing XFRM netlink socket [ 6.193429] NET: Registered protocol family 10 [ 6.197185] Segment Routing with IPv6 [ 6.198780] NET: Registered protocol family 17 [ 6.201789] mpls_gso: MPLS GSO support [ 6.207369] RAS: Correctable Errors collector initialized. [ 6.211148] AVX version of gcm_enc/dec engaged. [ 6.213502] AES CTR mode by8 optimization enabled [ 6.368263] sched_clock: Marking stable (6368006219, 0)->(7668021064, -1300014845) [ 6.418623] registered taskstats version 1 [ 6.420759] Loading compiled-in X.509 certificates [ 6.423995] zswap: loaded using pool lzo/zbud [ 6.464311] Key type big_key registered [ 6.478938] Key type encrypted registered [ 6.481117] ima: No TPM chip found, activating TPM-bypass! [ 6.485804] ima: Allocated hash algorithm: sha1 [ 6.488279] ima: No architecture policies found [ 6.490176] evm: Initialising EVM extended attributes: [ 6.492343] evm: security.selinux [ 6.493589] evm: security.ima [ 6.494458] evm: security.capability [ 6.495813] evm: HMAC attrs: 0x1 [ 6.498727] rtc_cmos 00:05: setting system clock to 2025-11-17 01:48:37 UTC (1763344117) [ 6.512334] debug: unmapping init [mem 0xffffffffa3a03000-0xffffffffa3bfffff] [ 6.522059] debug: unmapping init [mem 0xffffffffa2782000-0xffffffffa2a58fff] [ 6.530063] Write protecting the kernel read-only data: 28672k [ 6.532997] debug: unmapping init [mem 0xffffffffa0e03000-0xffffffffa0ffffff] [ 6.538630] debug: unmapping init [mem 0xffffffffa1714000-0xffffffffa17fffff] [ 6.584829] 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) [ 6.599460] systemd[1]: Detected virtualization kvm. [ 6.602240] systemd[1]: Detected architecture x86-64. [ 6.604616] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 6.636129] systemd[1]: No hostname configured. [ 6.639047] systemd[1]: Set hostname to . [ 6.641913] random: systemd: uninitialized urandom read (16 bytes read) [ 6.645868] systemd[1]: Initializing machine ID from random generator. [ 6.720335] random: ln: uninitialized urandom read (6 bytes read) [ 6.853860] random: systemd: uninitialized urandom read (16 bytes read) [ 6.857880] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ 6.867547] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 6.875283] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on Journal Socket (/dev/log). Starting Setup Virtual Console... [ OK ] Started Memstrack Anylazing Service. [ OK ] Reached target Initrd Root Device. [ OK ] Listening on udev Control Socket. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Timers. Starting Apply Kernel Variables... [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. Starting Journal Service... [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. 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... [ 8.006940] device-mapper: uevent: version 1.0.3 [ 8.011576] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 9.323741] virtio_net virtio0 ens2: renamed from eth0 [ 9.424991] scsi host0: ata_piix [ 9.439289] scsi host1: ata_piix [ 9.452323] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 9.455057] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 14.022896] random: crng init done [ 14.027491] random: 7 urandom warning(s) missed due to ratelimiting [ 16.324578] dracut-initqueue[577]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 18.267049] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Sockets. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 20.300859] printk: systemd: 26 output lines suppressed due to ratelimiting [ 21.099290] SELinux: Disabled at runtime. [ 21.186795] 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) [ 21.210590] systemd[1]: Detected virtualization kvm. [ 21.217588] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 22.744729] systemd[1]: initrd-switch-root.service: Succeeded. [ 22.760692] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 22.783972] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 22.797612] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 22.806817] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 22.836459] systemd[1]: Starting Journal Service... Starting Journal Service... [ 22.872553] systemd[1]: Stopped target Switch Root. [ OK ] Stopped target Switch Root. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Listening on RPCbind Server Activation Socket. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on Process Core Dump Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Reached target rpc_pipefs.target. [ OK ] Started Forward Password Requests to Wall Directory Watch. Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Local Encrypted Volumes. Mounting Huge Pages File System... [ OK ] Stopped target Initrd File Systems. [ OK ] Created slice system-getty.slice. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ 23.376244] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Mounting POSIX Message Queue File System... Starting Apply Kernel Variables... [ OK ] Stopped target Initrd Root File System. Starting Remount Root and Kernel File Systems... [ OK ] Reached target Paths. [ OK ] Listening on initctl Compatibility Named Pipe. Mounting Kernel Debug File System... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Reached target RPC Port Mapper. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Started Journal Service. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Apply Kernel Variables. [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. Starting Configure read-only root support... [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 24.159419] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Coldplug all Devices. [ OK ] Started udev Kernel Device Manager. [ 25.093588] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 25.118584] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 25.362780] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 25.389820] EDAC sbridge: Ver: 1.1.2 [ 28.278605] Key type dns_resolver registered [ 28.793345] NFS: Registering the id_resolver key type [ 28.798426] Key type id_resolver registered [ 28.801445] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started dnf makecache --timer. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Restore /run/initramfs on shutdown... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Reached target sshd-keygen.target. [ OK ] Started irqbalance daemon. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. Starting Login Service... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting Network Manager Wait Online... [ OK ] Started Login Service. 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 OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg427-client login: [ 44.224325] hrtimer: interrupt took 3312894 ns [ 69.695622] libcfs: loading out-of-tree module taints kernel. [ 69.829805] Key type ._llcrypt registered [ 69.838535] Key type .llcrypt registered [ 70.515971] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 70.533026] alg: No test for adler32 (adler32-zlib) [ 71.828294] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 72.832377] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 74.592197] Key type lgssc registered [ 76.147666] Lustre: Echo OBD driver; http://www.lustre.org/ [ 207.418879] Lustre: Mounted lustre-client [ 211.622485] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 227.891082] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing check_logdir /tmp/testlogs/ [ 231.115258] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing yml_node [ 232.928270] Lustre: lustre-OST0000-osc-ffff9d2b913db800: disconnect after 23s idle [ 234.895685] Lustre: DEBUG MARKER: Client: 2.16.61.46 [ 237.645314] Lustre: DEBUG MARKER: MDS: 2.16.61.46 [ 239.842467] Lustre: DEBUG MARKER: OSS: 2.16.61.46 [ 241.200283] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Sun Nov 16 20:52:31 EST 2025 [ 257.678620] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 258.858969] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 260.201933] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 270.445503] LustreError: 8979:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 270.489974] Lustre: Unmounted lustre-client [ 365.563813] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 368.660120] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 385.567133] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 415.868960] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 20:55:25 (1763344525) [ 418.011580] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 419.589353] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 20:55:29 (1763344529) [ 442.795244] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 445.813552] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 459.985865] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 476.204874] Lustre: Mounted lustre-client [ 479.210613] LustreError: 14421:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2ba02fe000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 479.238527] LustreError: 14421:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 479.241968] LustreError: 14421:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 479.295123] Lustre: Unmounted lustre-client [ 504.457939] Key type lgssc unregistered [ 504.734104] LNet: 14828:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 504.749651] LNetError: 14828:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 504.781317] LNet: Removed LNI 192.168.204.27@tcp [ 505.372668] Key type .llcrypt unregistered [ 505.378542] Key type ._llcrypt unregistered [ 517.793696] Key type ._llcrypt registered [ 517.795436] Key type .llcrypt registered [ 519.002895] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 20:57:08 (1763344628) [ 520.046034] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 520.063143] alg: No test for adler32 (adler32-zlib) [ 521.169248] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 521.398557] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 523.072182] Key type lgssc registered [ 523.934047] Lustre: Echo OBD driver; http://www.lustre.org/ [ 588.135662] Lustre: Mounted lustre-client [ 670.889232] LustreError: 18179:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b88bb9000: lov tgt 1990 not cleaned! deathrow=0, lovrc=1 [ 670.905696] LustreError: 18179:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 670.955324] Lustre: Unmounted lustre-client [ 695.815614] Key type lgssc unregistered [ 696.080976] LNet: 18643:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 696.085895] LNetError: 18643:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 697.126971] LNet: Removed LNI 192.168.204.27@tcp [ 697.999101] Key type .llcrypt unregistered [ 698.001601] Key type ._llcrypt unregistered [ 707.683165] Key type ._llcrypt registered [ 707.685373] Key type .llcrypt registered [ 709.042657] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 21:00:18 (1763344818) [ 711.239460] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 711.351452] alg: No test for adler32 (adler32-zlib) [ 712.601829] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 712.869183] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 714.552404] Key type lgssc registered [ 715.975476] Lustre: Echo OBD driver; http://www.lustre.org/ [ 761.769469] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 765.953056] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 783.582476] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 786.157589] Lustre: Mounted lustre-client [ 795.255178] LustreError: 21767:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b913dc000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 795.274701] LustreError: 21767:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 795.339261] Lustre: Unmounted lustre-client [ 819.678871] Key type lgssc unregistered [ 819.924384] LNet: 22170:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 819.930788] LNetError: 22170:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 819.944618] LNet: Removed LNI 192.168.204.27@tcp [ 820.469830] Key type .llcrypt unregistered [ 820.476705] Key type ._llcrypt unregistered [ 827.165990] Key type ._llcrypt registered [ 827.167648] Key type .llcrypt registered [ 828.310965] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 21:02:18 (1763344938) [ 830.595836] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 830.618745] alg: No test for adler32 (adler32-zlib) [ 831.745844] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 831.964443] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 833.624121] Key type lgssc registered [ 834.742498] Lustre: Echo OBD driver; http://www.lustre.org/ [ 871.871526] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 875.117147] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 890.420246] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 898.085368] Lustre: Mounted lustre-client [ 905.023368] LustreError: 24870:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b91353000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 905.069239] LustreError: 24870:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 905.142951] Lustre: Unmounted lustre-client [ 931.634495] Key type lgssc unregistered [ 931.819531] LNet: 25272:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 931.823349] LNetError: 25272:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 932.849182] LNet: Removed LNI 192.168.204.27@tcp [ 933.483831] Key type .llcrypt unregistered [ 933.489471] Key type ._llcrypt unregistered [ 946.821483] Key type ._llcrypt registered [ 946.823273] Key type .llcrypt registered [ 947.739407] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 21:04:17 (1763345057) [ 949.632325] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 949.678761] alg: No test for adler32 (adler32-zlib) [ 950.643600] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 950.801835] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 952.432197] Key type lgssc registered [ 953.524558] Lustre: Echo OBD driver; http://www.lustre.org/ [ 993.050894] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 995.774526] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1010.941924] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1023.059806] Lustre: Mounted lustre-client [ 1028.076977] Lustre: lustre-MDT0000-mdc-ffff9d2b868f7000: Connection to lustre-MDT0000 (at 192.168.204.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1038.310099] Lustre: lustre-MDT0001-mdc-ffff9d2b868f7000: Connection to lustre-MDT0001 (at 192.168.204.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1045.362330] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1054.560193] Lustre: 26230:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763345149/real 1763345149] req@00000000598fb689 x1849001310690304/t0(0) o400->MGC192.168.204.127@tcp@192.168.204.127@tcp:26/25 lens 224/224 e 0 to 1 dl 1763345165 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1054.577710] LustreError: MGC192.168.204.127@tcp: Connection to MGS (at 192.168.204.127@tcp) was lost; in progress operations using this service will fail [ 1055.667417] LustreError: 28448:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1055.722588] Lustre: Unmounted lustre-client [ 1072.462761] Key type lgssc unregistered [ 1072.665642] LNet: 28838:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1072.680508] LNetError: 28838:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1072.705841] LNet: Removed LNI 192.168.204.27@tcp [ 1073.238352] Key type .llcrypt unregistered [ 1073.247397] Key type ._llcrypt unregistered [ 1084.996921] Key type ._llcrypt registered [ 1084.998550] Key type .llcrypt registered [ 1086.123852] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 21:06:35 (1763345195) [ 1088.409516] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1088.433970] alg: No test for adler32 (adler32-zlib) [ 1089.490801] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 1089.732537] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 1091.376193] Key type lgssc registered [ 1092.428795] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1129.271673] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1132.671552] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1149.632655] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1156.634503] Lustre: Mounted lustre-client [ 1161.709564] Lustre: lustre-OST0000-osc-ffff9d2b8553a000: Connection to lustre-OST0000 (at 192.168.204.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1167.559910] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1177.854316] LustreError: 31960:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1177.885919] Lustre: Unmounted lustre-client [ 1209.043257] Key type lgssc unregistered [ 1209.262520] LNet: 32362:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1209.272793] LNetError: 32362:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1209.291341] LNet: Removed LNI 192.168.204.27@tcp [ 1209.867785] Key type .llcrypt unregistered [ 1209.874934] Key type ._llcrypt unregistered [ 1223.815798] Key type ._llcrypt registered [ 1223.817616] Key type .llcrypt registered [ 1225.352668] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 21:08:54 (1763345334) [ 1227.587796] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1227.605656] alg: No test for adler32 (adler32-zlib) [ 1228.688211] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 1228.989227] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 1230.664432] Key type lgssc registered [ 1232.067431] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1275.349658] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1278.643523] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1293.772805] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1300.515135] Lustre: Mounted lustre-client [ 1303.673927] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1306.612434] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1317.843101] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2025-11-1621h10m27s [ 1319.549076] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1320.940800] Lustre: lustre-MDT0000-mdc-ffff9d2b9829d800: Connection to lustre-MDT0000 (at 192.168.204.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1337.355297] LustreError: 36068:0:(lmv_obd.c:1435:lmv_statfs()) lustre-MDT0000-mdc-ffff9d2b9829d800: can't stat MDS #0: rc = -19 [ 1338.694781] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1345.518181] LustreError: MGC192.168.204.127@tcp: Connection to MGS (at 192.168.204.127@tcp) was lost; in progress operations using this service will fail [ 1345.551576] Lustre: Evicted from MGS (at 192.168.204.127@tcp) after server handle changed from 0x30f74cbb0f473e3d to 0x30f74cbb0f4741b6 [ 1345.571580] Lustre: MGC192.168.204.127@tcp: Connection restored to 192.168.204.127@tcp (at 192.168.204.127@tcp) [ 1346.609524] LustreError: lustre-MDT0000-mdc-ffff9d2b9829d800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1346.636691] Lustre: lustre-MDT0000-mdc-ffff9d2b9829d800: Connection restored to 192.168.204.127@tcp (at 192.168.204.127@tcp) [ 1351.102584] Lustre: DEBUG MARKER: Wait for df (36068) ... [ 1352.517339] Lustre: DEBUG MARKER: done [ 1354.339970] LustreError: 36681:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b9829d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1354.345499] LustreError: 36681:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1354.407401] Lustre: Unmounted lustre-client [ 1379.733797] Key type lgssc unregistered [ 1379.987285] LNet: 37084:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1380.003428] LNetError: 37084:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1380.025186] LNet: Removed LNI 192.168.204.27@tcp [ 1380.580056] Key type .llcrypt unregistered [ 1380.581783] Key type ._llcrypt unregistered [ 1418.654147] Key type ._llcrypt registered [ 1418.658862] Key type .llcrypt registered [ 1419.070372] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1419.098792] alg: No test for adler32 (adler32-zlib) [ 1420.123562] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 1420.295973] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 1421.968675] Key type lgssc registered [ 1422.985609] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1490.988650] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1494.344865] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1514.575681] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1553.507901] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 21:14:22 (1763345662) [ 1581.163611] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1585.296755] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1604.391924] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1610.264091] Lustre: Mounted lustre-client [ 1614.326932] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2025-11-1621h15m23s [ 1616.080584] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1622.541045] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1625.336151] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1645.544147] Lustre: 43969:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763345736/real 1763345736] req@0000000089c09544 x1849001803520384/t0(0) o101->lustre-MDT0000-mdc-ffff9d2b8415f000@192.168.204.127@tcp:12/10 lens 576/1152 e 0 to 1 dl 1763345756 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:0 [ 1645.575182] Lustre: lustre-MDT0000-mdc-ffff9d2b8415f000: Connection to lustre-MDT0000 (at 192.168.204.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1645.611543] Lustre: lustre-MDT0000-mdc-ffff9d2b8415f000: Connection restored to 192.168.204.127@tcp (at 192.168.204.127@tcp) [ 1667.026675] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1670.568934] Lustre: DEBUG MARKER: done [ 1674.027359] LustreError: 44458:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b8415f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1674.052967] LustreError: 44458:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1674.095864] Lustre: Unmounted lustre-client [ 1697.195132] Key type lgssc unregistered [ 1697.462152] LNet: 44862:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1697.477201] LNetError: 44862:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1697.504594] LNet: Removed LNI 192.168.204.27@tcp [ 1698.124593] Key type .llcrypt unregistered [ 1698.130497] Key type ._llcrypt unregistered [ 1738.570569] Key type ._llcrypt registered [ 1738.587822] Key type .llcrypt registered [ 1738.997051] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1739.021373] alg: No test for adler32 (adler32-zlib) [ 1740.075109] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 1740.260755] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 1741.920433] Key type lgssc registered [ 1743.168512] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1810.446292] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1813.701850] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1832.908400] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1868.551595] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 21:19:38 (1763345978) [ 1869.852862] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1871.295318] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 21:19:41 (1763345981) [ 1893.802806] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 21:20:03 (1763346003) [ 1919.541082] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1923.073832] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1938.538868] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1949.783871] Lustre: Mounted lustre-client [ 1953.202303] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 1957.036476] LustreError: 52704:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2ba0209800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1957.073928] LustreError: 52704:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1957.157340] Lustre: Unmounted lustre-client [ 1966.207363] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 2007.683369] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2010.907106] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2011.628640] Lustre: Mounted lustre-client [ 2014.911110] LustreError: 53964:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b89633800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2014.945731] LustreError: 53964:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2014.949324] LustreError: 53964:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 2015.045631] Lustre: Unmounted lustre-client [ 2067.936329] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2071.838745] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2072.356936] Lustre: Mounted lustre-client [ 2075.607785] LustreError: 55086:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b8c291800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2075.647149] LustreError: 55086:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2075.650626] LustreError: 55086:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 2075.717461] Lustre: Unmounted lustre-client [ 2092.891929] Lustre: DEBUG MARKER: files compared the same [ 2106.478437] Key type lgssc unregistered [ 2106.758183] LNet: 55787:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2106.771246] LNetError: 55787:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2106.790964] LNet: Removed LNI 192.168.204.27@tcp [ 2107.704818] Key type .llcrypt unregistered [ 2107.710286] Key type ._llcrypt unregistered [ 2123.277747] Key type ._llcrypt registered [ 2123.279594] Key type .llcrypt registered [ 2124.712655] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 21:23:54 (1763346234) [ 2127.512015] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2127.547082] alg: No test for adler32 (adler32-zlib) [ 2128.638622] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 2128.815725] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 2130.473289] Key type lgssc registered [ 2131.701369] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2177.331113] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2180.831850] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2198.194398] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2204.210467] Lustre: Mounted lustre-client [ 2207.668238] LustreError: 58882:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b868f4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2207.704833] LustreError: 58882:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2207.771950] Lustre: Unmounted lustre-client [ 2233.000339] Key type lgssc unregistered [ 2233.231347] LNet: 59284:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2233.242353] LNetError: 59284:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2234.281167] LNet: Removed LNI 192.168.204.27@tcp [ 2234.967103] Key type .llcrypt unregistered [ 2234.969156] Key type ._llcrypt unregistered [ 2249.606756] Key type ._llcrypt registered [ 2249.611621] Key type .llcrypt registered [ 2251.085607] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 21:26:00 (1763346360) [ 2253.593836] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2253.617704] alg: No test for adler32 (adler32-zlib) [ 2254.794765] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 2255.083583] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 2256.769286] Key type lgssc registered [ 2258.456322] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2337.363239] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2362.887269] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2366.649792] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2392.055317] Key type lgssc unregistered [ 2392.286582] LNet: 62679:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2392.301183] LNetError: 62679:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2392.317959] LNet: Removed LNI 192.168.204.27@tcp [ 2392.948501] Key type .llcrypt unregistered [ 2392.950289] Key type ._llcrypt unregistered [ 2407.260149] Key type ._llcrypt registered [ 2407.261725] Key type .llcrypt registered [ 2408.497897] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 21:28:38 (1763346518) [ 2412.239415] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2412.258289] alg: No test for adler32 (adler32-zlib) [ 2413.299413] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 2413.478524] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 2415.144252] Key type lgssc registered [ 2416.344280] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2484.712418] Lustre: Mounted lustre-client [ 2491.276360] LustreError: 65168:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b88313800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2491.301314] LustreError: 65168:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2491.411581] Lustre: Unmounted lustre-client [ 2526.315184] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 21:30:35 (1763346635) [ 2633.563621] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2653.277580] Lustre: Mounted lustre-client [ 2659.776955] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2659.784570] LustreError: 69137:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff9d2b8c653800: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 2659.806618] Lustre: 63659:0:(llite_lib.c:4226:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.204.127@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 2670.076203] LustreError: 69137:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2670.084184] LustreError: 69137:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 2670.165616] Lustre: Unmounted lustre-client [ 2713.256510] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 21:33:42 (1763346822) [ 2723.478480] Key type lgssc unregistered [ 2723.742316] LNet: 70346:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2723.745966] LNetError: 70346:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2723.765428] LNet: Removed LNI 192.168.204.27@tcp [ 2724.292772] Key type .llcrypt unregistered [ 2724.298839] Key type ._llcrypt unregistered [ 2733.135657] Key type ._llcrypt registered [ 2733.137151] Key type .llcrypt registered [ 2733.418867] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2733.430435] alg: No test for adler32 (adler32-zlib) [ 2734.514681] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 2734.705872] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 2736.360176] Key type lgssc registered [ 2737.396375] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2776.823686] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2780.620437] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2799.469938] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2805.282499] Lustre: Mounted lustre-client [ 2808.521611] LustreError: 72869:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b884a0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2808.553893] LustreError: 72869:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2808.678171] Lustre: Unmounted lustre-client [ 2843.227816] Key type lgssc unregistered [ 2843.585958] LNet: 73272:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2843.591131] LNetError: 73272:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2843.616788] LNet: Removed LNI 192.168.204.27@tcp [ 2844.339398] Key type .llcrypt unregistered [ 2844.341712] Key type ._llcrypt unregistered [ 2852.970215] Key type ._llcrypt registered [ 2852.971580] Key type .llcrypt registered [ 2853.338602] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2853.358249] alg: No test for adler32 (adler32-zlib) [ 2854.555716] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 2854.832855] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 2856.512315] Key type lgssc registered [ 2857.798338] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2955.362341] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2959.163887] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2975.537122] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2982.459664] Lustre: Mounted lustre-client [ 2983.890310] LustreError: 76224:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b8837a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2983.913569] LustreError: 76224:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2983.987670] Lustre: Unmounted lustre-client [ 3005.785185] Key type lgssc unregistered [ 3006.140497] LNet: 76626:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3006.148457] LNetError: 76626:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3006.165224] LNet: Removed LNI 192.168.204.27@tcp [ 3006.918375] Key type .llcrypt unregistered [ 3006.920179] Key type ._llcrypt unregistered [ 3022.982311] Key type ._llcrypt registered [ 3022.983877] Key type .llcrypt registered [ 3024.115868] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 21:38:53 (1763347133) [ 3026.671939] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3026.706267] alg: No test for adler32 (adler32-zlib) [ 3027.812774] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 3028.007174] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 3029.664166] Key type lgssc registered [ 3030.924545] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3069.099734] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3072.021791] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3088.243741] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3100.193030] Lustre: Mounted lustre-client [ 3104.329677] LustreError: 79752:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b90ff3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3104.354523] LustreError: 79752:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3104.403102] Lustre: Unmounted lustre-client [ 3104.805226] Lustre: Mounted lustre-client [ 3106.796411] LustreError: 79834:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b884a5800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3106.814160] LustreError: 79834:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3106.817425] LustreError: 79834:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 3106.884715] Lustre: Unmounted lustre-client [ 3141.007190] Key type lgssc unregistered [ 3141.233731] LNet: 80236:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3141.247842] LNetError: 80236:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3141.277233] LNet: Removed LNI 192.168.204.27@tcp [ 3141.834247] Key type .llcrypt unregistered [ 3141.837654] Key type ._llcrypt unregistered [ 3150.084531] Key type ._llcrypt registered [ 3150.085716] Key type .llcrypt registered [ 3150.529366] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3150.551078] alg: No test for adler32 (adler32-zlib) [ 3151.627439] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 3151.866979] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 3153.539442] Key type lgssc registered [ 3154.577464] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3188.087220] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3190.602359] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3202.644829] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3204.275421] Lustre: Mounted lustre-client [ 3211.238512] LustreError: 82776:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b868f5800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3211.277149] LustreError: 82776:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3211.346722] Lustre: Unmounted lustre-client [ 3235.236859] Key type lgssc unregistered [ 3235.466701] LNet: 83178:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3235.477496] LNetError: 83178:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3235.494342] LNet: Removed LNI 192.168.204.27@tcp [ 3236.018779] Key type .llcrypt unregistered [ 3236.022774] Key type ._llcrypt unregistered [ 3247.494783] Key type ._llcrypt registered [ 3247.496149] Key type .llcrypt registered [ 3248.823663] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 21:42:38 (1763347358) [ 3250.796444] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3250.808154] alg: No test for adler32 (adler32-zlib) [ 3251.898284] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 3252.087062] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 3253.792193] Key type lgssc registered [ 3254.761783] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3289.219633] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3291.768760] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3303.249946] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3305.695118] Lustre: Mounted lustre-client [ 3313.290170] Lustre: lustre: root_squash is set to 0:0 [ 3323.420616] Lustre: lustre: nosquash_nids is cleared [ 3323.437175] Lustre: lustre: root_squash is set to 500:500 [ 3329.096970] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.204.27@tcp 192.168.0.[2,10]@tcp [ 3337.232720] Lustre: lustre: nosquash_nids set to 192.168.204.27/24@tcp [ 3342.462415] LustreError: 86658:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b8415c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3342.491687] LustreError: 86658:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3342.524480] Lustre: Unmounted lustre-client [ 3364.998772] Key type lgssc unregistered [ 3365.227560] LNet: 87059:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3365.232991] LNetError: 87059:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3365.251411] LNet: Removed LNI 192.168.204.27@tcp [ 3365.885742] Key type .llcrypt unregistered [ 3365.890791] Key type ._llcrypt unregistered [ 3379.287725] Key type ._llcrypt registered [ 3379.289357] Key type .llcrypt registered [ 3380.318283] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 21:44:50 (1763347490) [ 3382.062460] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3383.233775] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 21:44:53 (1763347493) [ 3384.447855] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3384.508599] alg: No test for adler32 (adler32-zlib) [ 3385.475055] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 3385.615574] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 3387.233819] Key type lgssc registered [ 3388.134126] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3421.167449] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3423.959364] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3441.340940] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3442.818192] Lustre: lustre: root_squash is set to 500:500 [ 3442.872255] Lustre: lustre: nosquash_nids set to 192.168.204.27/24@tcp [ 3453.479838] Lustre: Mounted lustre-client [ 3457.989531] LustreError: 90542:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b8c656800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3458.012160] LustreError: 90542:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 3458.082706] Lustre: Unmounted lustre-client [ 3481.474209] Key type lgssc unregistered [ 3481.708701] LNet: 90944:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3481.719286] LNetError: 90944:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3481.769212] LNet: Removed LNI 192.168.204.27@tcp [ 3482.175777] Key type .llcrypt unregistered [ 3482.177933] Key type ._llcrypt unregistered [ 3491.386072] Key type ._llcrypt registered [ 3491.387797] Key type .llcrypt registered [ 3492.227503] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 3493.425826] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 21:46:43 (1763347603) [ 3505.160508] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3505.187795] alg: No test for adler32 (adler32-zlib) [ 3506.188216] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 3506.376843] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 3508.056171] Key type lgssc registered [ 3509.121683] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3566.286678] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3568.709191] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3586.143756] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3634.079809] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3636.282495] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3648.623448] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3654.924254] Lustre: Mounted lustre-client [ 3677.022987] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9d2b91357000.ost_server_uuid 50 [ 3678.165765] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9d2b91357000.ost_server_uuid in FULL state after 0 sec [ 3678.608106] Lustre: Mounted lustre-client [ 3681.110354] LustreError: 97837:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b8800e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3681.129439] LustreError: 97837:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 3681.225696] Lustre: Unmounted lustre-client [ 3682.107042] LustreError: 97856:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b91357000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3682.117672] LustreError: 97856:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 3682.143512] LustreError: 97856:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 3682.148030] LustreError: 97856:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 3682.236671] Lustre: Unmounted lustre-client [ 3720.931220] Key type lgssc unregistered [ 3721.171886] LNet: 98409:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3721.182718] LNetError: 98409:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3721.204991] LNet: Removed LNI 192.168.204.27@tcp [ 3721.812394] Key type .llcrypt unregistered [ 3721.814220] Key type ._llcrypt unregistered [ 3752.603776] Key type ._llcrypt registered [ 3752.605479] Key type .llcrypt registered [ 3752.871884] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3752.898039] alg: No test for adler32 (adler32-zlib) [ 3753.996936] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 3754.205187] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 3755.864208] Key type lgssc registered [ 3757.017869] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3821.379714] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3825.068799] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3843.849153] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3877.042685] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 21:53:06 (1763347986) [ 3947.474785] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3949.824475] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3964.023780] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3965.699069] Lustre: Mounted lustre-client [ 3986.411923] Lustre: lustre-OST0000-osc-ffff9d2b913d7000: Connection to lustre-OST0000 (at 192.168.204.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3987.431343] Lustre: 99198:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763348082/real 1763348082] req@00000000dc7bfc05 x1849004250895488/t0(0) o400->lustre-OST0000-osc-ffff9d2b913d7000@192.168.204.127@tcp:28/4 lens 224/224 e 0 to 1 dl 1763348098 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3988.237808] Lustre: lustre-OST0000-osc-ffff9d2b913d7000: Connection restored to 192.168.204.127@tcp (at 192.168.204.127@tcp) [ 3992.608172] Lustre: 99198:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763348087/real 1763348087] req@00000000147a3055 x1849004250896000/t0(0) o400->lustre-OST0000-osc-ffff9d2b913d7000@192.168.204.127@tcp:28/4 lens 224/224 e 0 to 1 dl 1763348103 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 3996.644429] Lustre: lustre-MDT0000-mdc-ffff9d2b913d7000: Connection to lustre-MDT0000 (at 192.168.204.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3997.728946] Lustre: 99198:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763348092/real 1763348092] req@00000000aec31562 x1849004250896512/t0(0) o400->lustre-OST0000-osc-ffff9d2b913d7000@192.168.204.127@tcp:28/4 lens 224/224 e 0 to 1 dl 1763348108 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4012.000164] Lustre: 99196:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763348107/real 1763348107] req@0000000041cd1fe7 x1849004250898176/t0(0) o400->MGC192.168.204.127@tcp@192.168.204.127@tcp:26/25 lens 224/224 e 0 to 1 dl 1763348123 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4012.019128] LustreError: MGC192.168.204.127@tcp: Connection to MGS (at 192.168.204.127@tcp) was lost; in progress operations using this service will fail [ 4012.042801] Lustre: Evicted from MGS (at 192.168.204.127@tcp) after server handle changed from 0x123a560182f7518 to 0x123a560182f7795 [ 4012.057657] Lustre: MGC192.168.204.127@tcp: Connection restored to 192.168.204.127@tcp (at 192.168.204.127@tcp) [ 4016.186551] Lustre: lustre-MDT0000-mdc-ffff9d2b913d7000: Connection restored to 192.168.204.127@tcp (at 192.168.204.127@tcp) [ 4017.624349] LustreError: 107064:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b913d7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4017.642150] LustreError: 107064:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4017.689059] Lustre: Unmounted lustre-client [ 4039.072866] Key type lgssc unregistered [ 4039.220285] LNet: 107465:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4039.224916] LNetError: 107465:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4039.234068] LNet: Removed LNI 192.168.204.27@tcp [ 4039.637157] Key type .llcrypt unregistered [ 4039.640759] Key type ._llcrypt unregistered [ 4048.366486] Key type ._llcrypt registered [ 4048.368237] Key type .llcrypt registered [ 4049.081788] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 21:55:59 (1763348159) [ 4061.775702] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4061.789771] alg: No test for adler32 (adler32-zlib) [ 4062.693529] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 4062.809499] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 4064.424221] Key type lgssc registered [ 4065.049914] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4088.864697] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4148.971095] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4151.088790] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4166.145861] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4168.587090] Lustre: Mounted lustre-client [ 4169.662201] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 4170.653369] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 21:58:00 (1763348280) [ 4352.942802] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4363.251321] LustreError: 118237:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4363.274544] Lustre: Unmounted lustre-client [ 4465.832618] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4468.391168] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4481.265372] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4510.284344] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 22:03:40 (1763348620) [ 4577.237063] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4580.391071] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4595.535345] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4596.073330] Lustre: Mounted lustre-client [ 4599.038322] LustreError: 125652:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b874c2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4599.063196] LustreError: 125652:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4599.071525] LustreError: 125652:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4599.121113] Lustre: Unmounted lustre-client [ 4625.381277] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 22:05:35 (1763348735) [ 4695.805702] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4699.642972] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4715.937322] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4716.510120] Lustre: Mounted lustre-client [ 4719.615170] LustreError: 129701:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b8823b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4719.637817] LustreError: 129701:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4719.641024] LustreError: 129701:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4719.703519] Lustre: Unmounted lustre-client [ 4744.661578] Key type lgssc unregistered [ 4744.874717] LNet: 130103:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4744.880926] LNetError: 130103:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4744.900980] LNet: Removed LNI 192.168.204.27@tcp [ 4745.438409] Key type .llcrypt unregistered [ 4745.440498] Key type ._llcrypt unregistered [ 4756.018688] Key type ._llcrypt registered [ 4756.020302] Key type .llcrypt registered [ 4757.091553] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 22:07:46 (1763348866) [ 4758.761767] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4758.789287] alg: No test for adler32 (adler32-zlib) [ 4759.881987] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 4760.118553] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 4761.811132] Key type lgssc registered [ 4762.970771] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4805.962032] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4809.696342] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4827.723240] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4830.340205] Lustre: Mounted lustre-client [ 4842.552976] LustreError: 133208:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b874c6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4842.572579] LustreError: 133208:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4842.619640] Lustre: Unmounted lustre-client [ 4867.488182] Key type lgssc unregistered [ 4867.713463] LNet: 133609:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4867.722481] LNetError: 133609:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4867.746154] LNet: Removed LNI 192.168.204.27@tcp [ 4868.425283] Key type .llcrypt unregistered [ 4868.426854] Key type ._llcrypt unregistered [ 4881.225425] Key type ._llcrypt registered [ 4881.230299] Key type .llcrypt registered [ 4882.351178] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 22:09:52 (1763348992) [ 4884.406162] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4884.474807] alg: No test for adler32 (adler32-zlib) [ 4885.556412] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 4885.739694] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 4887.384527] Key type lgssc registered [ 4888.394451] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4931.618756] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4935.780373] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4950.073333] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4956.704965] Lustre: Mounted lustre-client [ 4961.778474] Lustre: lustre-OST0000-osc-ffff9d2b868f2800: Connection to lustre-OST0000 (at 192.168.204.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4979.281989] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff9d2b868f2800.ost_server_uuid 50 [ 4981.248428] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 4992.112197] LustreError: 137148:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b868f2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4992.154053] LustreError: 137148:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4992.268212] Lustre: Unmounted lustre-client [ 5014.710933] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 22:12:04 (1763349124) [ 5042.439851] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5046.070270] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5063.266745] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5079.400924] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5080.107870] Lustre: Mounted lustre-client [ 5085.164658] Lustre: lustre-OST0000-osc-ffff9d2b91351800: Connection to lustre-OST0000 (at 192.168.204.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5105.922619] LustreError: 139808:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b91351800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5105.961789] LustreError: 139808:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 5105.968760] LustreError: 139808:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5106.019653] Lustre: Unmounted lustre-client [ 5158.950731] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 22:14:28 (1763349268) [ 5185.642200] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5189.060965] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5201.873395] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5216.498700] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5217.138179] Lustre: Mounted lustre-client [ 5222.383283] Lustre: lustre-OST0000-osc-ffff9d2b866f6000: Connection to lustre-OST0000 (at 192.168.204.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5244.575797] LustreError: 142570:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b866f6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5244.584196] LustreError: 142570:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 5244.600105] LustreError: 142570:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 5244.608739] LustreError: 142570:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 5244.664682] Lustre: Unmounted lustre-client [ 5291.350940] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 22:16:41 (1763349401) [ 5371.373976] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5374.518511] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5393.551602] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5452.695454] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5455.393425] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5469.562915] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5492.117262] Lustre: Mounted lustre-client [ 5528.354234] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5539.513858] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 9 sec [ 5541.219584] LustreError: 149245:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b91351000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5541.233131] LustreError: 149245:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 5541.245061] LustreError: 149245:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5541.247937] LustreError: 149245:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 5541.307697] Lustre: Unmounted lustre-client [ 5570.769985] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 22:21:20 (1763349680) [ 5594.050325] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5596.712901] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5613.421819] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5643.820763] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5679.800102] Lustre: Mounted lustre-client [ 5716.063736] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5717.382966] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 5721.076050] Lustre: lustre-OST0001-osc-ffff9d2b90a95000: Connection to lustre-OST0001 (at 192.168.204.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5728.435597] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5738.736725] LustreError: 152826:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 5738.750336] LustreError: 152826:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5738.794722] Lustre: Unmounted lustre-client [ 5788.372944] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 22:24:57 (1763349897) [ 5816.950328] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5820.416534] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5837.484320] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5838.056640] Lustre: Mounted lustre-client [ 5857.934822] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5859.292314] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 5870.423209] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9d2ba02fa000.ost_server_uuid 50 [ 5871.814681] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9d2ba02fa000.ost_server_uuid in FULL state after 0 sec [ 5874.002700] LustreError: 156252:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2ba02fa000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5874.028928] LustreError: 156252:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 5874.035552] LustreError: 156252:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 5874.115513] Lustre: Unmounted lustre-client [ 5874.373125] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 5874.377178] Lustre: Skipped 3 previous similar messages [ 5874.513083] Lustre: Mounted lustre-client [ 5875.364519] LustreError: 156316:0:(sec.c:410:import_sec_validate_get()) import 0000000096881b2f (NEW) with no sec [ 5926.053304] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 22:27:15 (1763350035) [ 5949.416170] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5952.572952] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5969.279711] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5985.634160] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5986.205047] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 5986.410429] Lustre: Mounted lustre-client [ 5996.159355] LustreError: 159152:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b874c7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5996.170442] LustreError: 159152:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 5996.199934] LustreError: 159152:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 5996.204119] LustreError: 159152:0:(obd_class.h:479:obd_check_dev()) Skipped 18 previous similar messages [ 5996.255180] Lustre: Unmounted lustre-client [ 5996.260180] Lustre: Skipped 1 previous similar message [ 6039.824512] Key type lgssc unregistered [ 6040.075359] LNet: 159601:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6040.086134] LNetError: 159601:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6040.108489] LNet: Removed LNI 192.168.204.27@tcp [ 6040.666958] Key type .llcrypt unregistered [ 6040.668565] Key type ._llcrypt unregistered [ 6053.221633] Key type ._llcrypt registered [ 6053.224428] Key type .llcrypt registered [ 6054.313370] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 22:29:24 (1763350164) [ 6055.075513] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6055.091197] alg: No test for adler32 (adler32-zlib) [ 6056.121560] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 6056.448532] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 6058.137798] Key type lgssc registered [ 6059.278659] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6105.154861] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6108.711445] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6126.349129] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6145.885243] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6146.523862] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 6147.719809] Lustre: Mounted lustre-client [ 6154.268565] LustreError: lustre-MDT0001-mdc-ffff9d2b884a2000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 6154.300247] Lustre: lustre-MDT0001-mdc-ffff9d2b884a2000: Connection restored to 192.168.204.127@tcp (at 192.168.204.127@tcp) [ 6158.262667] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6160.614298] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6179.868836] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 6183.271894] LustreError: 163837:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b884a2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6183.287444] LustreError: 163837:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 6183.359583] Lustre: Unmounted lustre-client [ 6210.944901] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 22:32:00 (1763350320) [ 6294.427996] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6298.075495] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6317.445660] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6318.238980] Lustre: Mounted lustre-client [ 6339.442784] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6340.853771] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6345.716851] Lustre: lustre-OST0001-osc-ffff9d2b90d80000: Connection to lustre-OST0001 (at 192.168.204.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6351.581851] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6361.841954] LustreError: 168576:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 6361.849928] LustreError: 168576:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 6361.904467] Lustre: Unmounted lustre-client [ 6404.521473] Key type lgssc unregistered [ 6404.821343] LNet: 168982:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6404.838332] LNetError: 168982:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6404.868229] LNet: Removed LNI 192.168.204.27@tcp [ 6405.679881] Key type .llcrypt unregistered [ 6405.682103] Key type ._llcrypt unregistered [ 6449.226148] Key type ._llcrypt registered [ 6449.227884] Key type .llcrypt registered [ 6449.765481] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6449.796380] alg: No test for adler32 (adler32-zlib) [ 6450.873545] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 6451.049503] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 6452.752280] Key type lgssc registered [ 6453.846975] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6528.602824] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6532.134837] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6552.731482] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6589.721834] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 22:38:19 (1763350699) [ 6616.611337] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6620.140488] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6637.814468] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6643.751676] Lustre: Mounted lustre-client [ 6688.476519] LustreError: 175075:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b874c5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6688.495159] LustreError: 175075:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6688.539955] Lustre: Unmounted lustre-client [ 6728.477980] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6729.090064] Lustre: Mounted lustre-client [ 6765.666279] LustreError: 175736:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b8c653000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6765.694863] LustreError: 175736:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6765.702782] LustreError: 175736:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 6765.791245] Lustre: Unmounted lustre-client [ 6794.089528] Key type lgssc unregistered [ 6794.458217] LNet: 176142:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6794.476444] LNetError: 176142:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6794.499696] LNet: Removed LNI 192.168.204.27@tcp [ 6795.208402] Key type .llcrypt unregistered [ 6795.214251] Key type ._llcrypt unregistered [ 6809.908433] Key type ._llcrypt registered [ 6809.910073] Key type .llcrypt registered [ 6811.195749] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 22:42:00 (1763350920) [ 6813.307339] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6813.317364] alg: No test for adler32 (adler32-zlib) [ 6814.383860] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 6814.581734] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 6816.216264] Key type lgssc registered [ 6817.375179] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6860.133822] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6864.037840] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6884.008822] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6885.639302] Lustre: Mounted lustre-client [ 6903.495034] LustreError: 179401:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b884a6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6903.519677] LustreError: 179401:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6903.585748] Lustre: Unmounted lustre-client [ 6928.532693] Key type lgssc unregistered [ 6928.845847] LNet: 179803:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6928.859146] LNetError: 179803:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6928.873886] LNet: Removed LNI 192.168.204.27@tcp [ 6929.463778] Key type .llcrypt unregistered [ 6929.471771] Key type ._llcrypt unregistered [ 6940.014594] Key type ._llcrypt registered [ 6940.016466] Key type .llcrypt registered [ 6940.279178] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6940.291485] alg: No test for adler32 (adler32-zlib) [ 6941.445415] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 6941.667765] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 6943.384669] Key type lgssc registered [ 6944.496570] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6987.775645] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6991.475857] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7008.396092] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7014.415170] Lustre: Mounted lustre-client [ 7020.530990] LustreError: 182371:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b91355800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7020.558195] LustreError: 182371:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7020.608047] Lustre: Unmounted lustre-client [ 7046.707995] Key type lgssc unregistered [ 7046.977361] LNet: 182775:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7046.985893] LNetError: 182775:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7047.027384] LNet: Removed LNI 192.168.204.27@tcp [ 7047.642317] Key type .llcrypt unregistered [ 7047.646187] Key type ._llcrypt unregistered [ 7061.040983] Key type ._llcrypt registered [ 7061.042489] Key type .llcrypt registered [ 7062.300474] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 22:46:11 (1763351171) [ 7064.275153] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7064.320996] alg: No test for adler32 (adler32-zlib) [ 7065.496864] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 7065.766735] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 7067.444051] Key type lgssc registered [ 7068.740546] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7109.186642] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7112.496751] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7128.513822] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7135.247576] Lustre: Mounted lustre-client [ 7151.220213] LustreError: 186036:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b8c657800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7151.253342] LustreError: 186036:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7151.306035] Lustre: Unmounted lustre-client [ 7177.621833] Key type lgssc unregistered [ 7177.905817] LNet: 186438:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7177.912558] LNetError: 186438:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7177.936191] LNet: Removed LNI 192.168.204.27@tcp [ 7178.644155] Key type .llcrypt unregistered [ 7178.652716] Key type ._llcrypt unregistered [ 7191.833671] Key type ._llcrypt registered [ 7191.835135] Key type .llcrypt registered [ 7192.309763] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7192.325334] alg: No test for adler32 (adler32-zlib) [ 7193.496026] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 7193.753646] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 7195.408194] Key type lgssc registered [ 7196.470550] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7242.800461] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7245.782507] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7260.807231] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7266.879876] Lustre: Mounted lustre-client [ 7272.389074] LustreError: 189011:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2ba020d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7272.418320] LustreError: 189011:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7272.475396] Lustre: Unmounted lustre-client [ 7297.076204] Key type lgssc unregistered [ 7297.360574] LNet: 189414:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7297.370739] LNetError: 189414:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7297.393388] LNet: Removed LNI 192.168.204.27@tcp [ 7298.006322] Key type .llcrypt unregistered [ 7298.011470] Key type ._llcrypt unregistered [ 7310.502694] Key type ._llcrypt registered [ 7310.504377] Key type .llcrypt registered [ 7311.793791] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 22:50:21 (1763351421) [ 7326.603950] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7326.640503] alg: No test for adler32 (adler32-zlib) [ 7327.664628] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 7327.819829] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 7329.448241] Key type lgssc registered [ 7330.398498] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7390.786129] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7393.592892] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7409.373625] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7452.821733] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 22:52:42 (1763351562) [ 7476.148140] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7479.381332] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7495.395172] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7506.457646] Lustre: Mounted lustre-client [ 7509.832152] LustreError: 195927:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b866f2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7509.852799] LustreError: 195927:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7509.907252] Lustre: Unmounted lustre-client [ 7537.428576] Key type lgssc unregistered [ 7537.797256] LNet: 196331:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7537.813619] LNetError: 196331:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7537.845616] LNet: Removed LNI 192.168.204.27@tcp [ 7538.537819] Key type .llcrypt unregistered [ 7538.545757] Key type ._llcrypt unregistered [ 7552.345053] Key type ._llcrypt registered [ 7552.350140] Key type .llcrypt registered [ 7553.700959] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 7555.270657] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 22:54:24 (1763351664) [ 7571.902866] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7571.916059] alg: No test for adler32 (adler32-zlib) [ 7572.956602] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 7573.138237] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 7574.840345] Key type lgssc registered [ 7575.975511] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7653.144705] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7656.493860] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7673.849370] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid [ 7690.735776] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid [ 7693.519659] Lustre: Mounted lustre-client [ 7710.149558] LustreError: 201868:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b90a94800: lov tgt 1000 not cleaned! deathrow=0, lovrc=1 [ 7710.170573] LustreError: 201868:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7710.278720] Lustre: Unmounted lustre-client [ 7814.531665] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7817.204641] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7832.600691] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7865.355996] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 22:59:35 (1763351975) [ 7866.715182] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 7867.876971] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 22:59:37 (1763351977) [ 7907.978242] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7910.410822] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7940.080943] Key type lgssc unregistered [ 7940.281642] LNet: 208047:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7940.286099] LNetError: 208047:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7940.298170] LNet: Removed LNI 192.168.204.27@tcp [ 7940.761256] Key type .llcrypt unregistered [ 7940.762942] Key type ._llcrypt unregistered [ 7950.051912] Key type ._llcrypt registered [ 7950.053921] Key type .llcrypt registered [ 7951.036991] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 23:01:00 (1763352060) [ 7973.320714] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7973.336678] alg: No test for adler32 (adler32-zlib) [ 7974.292402] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 7974.433990] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 7976.064179] Key type lgssc registered [ 7976.806351] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8005.096893] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8007.764495] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8019.502310] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8042.802604] Key type lgssc unregistered [ 8043.016691] LNet: 211683:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8043.022800] LNetError: 211683:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8043.038930] LNet: Removed LNI 192.168.204.27@tcp [ 8043.474661] Key type .llcrypt unregistered [ 8043.478480] Key type ._llcrypt unregistered [ 8053.196728] Key type ._llcrypt registered [ 8053.201834] Key type .llcrypt registered [ 8054.234229] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 23:02:44 (1763352164) [ 8056.155883] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8056.166499] alg: No test for adler32 (adler32-zlib) [ 8057.143515] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 8057.290486] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 8058.937069] Key type lgssc registered [ 8059.688491] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8091.002502] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8093.706665] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8105.147943] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8107.569490] Lustre: Mounted lustre-client [ 8117.735981] Lustre: lustre-MDT0000-mdc-ffff9d2b913d9000: Connection to lustre-MDT0000 (at 192.168.204.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8122.870543] Lustre: lustre-MDT0001-mdc-ffff9d2b913d9000: Connection to lustre-MDT0001 (at 192.168.204.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8138.016203] Lustre: 212668:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763352233/real 1763352233] req@00000000469db73f x1849008762994944/t0(0) o400->MGC192.168.204.127@tcp@192.168.204.127@tcp:26/25 lens 224/224 e 0 to 1 dl 1763352249 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 8138.033692] LustreError: MGC192.168.204.127@tcp: Connection to MGS (at 192.168.204.127@tcp) was lost; in progress operations using this service will fail [ 8148.327383] Lustre: Evicted from MGS (at 192.168.204.127@tcp) after server handle changed from 0xc2396e682d31146a to 0xc2396e682d312f6e [ 8148.333317] Lustre: MGC192.168.204.127@tcp: Connection restored to 192.168.204.127@tcp (at 192.168.204.127@tcp) [ 8158.760126] LustreError: lustre-MDT0000-mdc-ffff9d2b913d9000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 8158.787532] Lustre: lustre-MDT0000-mdc-ffff9d2b913d9000: Connection restored to 192.168.204.127@tcp (at 192.168.204.127@tcp) [ 8161.283207] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8162.304774] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 8164.490829] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8165.602722] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 8167.045917] LustreError: 216087:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b913d9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8167.054653] LustreError: 216087:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8167.095921] Lustre: Unmounted lustre-client [ 8187.333447] Key type lgssc unregistered [ 8187.490189] LNet: 216489:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8187.494503] LNetError: 216489:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8187.506101] LNet: Removed LNI 192.168.204.27@tcp [ 8187.886705] Key type .llcrypt unregistered [ 8187.888479] Key type ._llcrypt unregistered [ 8195.560249] Key type ._llcrypt registered [ 8195.561981] Key type .llcrypt registered [ 8196.425281] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 23:05:06 (1763352306) [ 8197.867124] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8197.899355] alg: No test for adler32 (adler32-zlib) [ 8198.847321] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 8198.978168] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 8200.616221] Key type lgssc registered [ 8201.324675] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8226.437714] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8228.119855] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8255.303174] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8257.146996] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8272.779752] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8284.014329] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 8313.903411] Key type lgssc unregistered [ 8314.053979] LNet: 221589:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8314.061040] LNetError: 221589:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8314.072812] LNet: Removed LNI 192.168.204.27@tcp [ 8314.486459] Key type .llcrypt unregistered [ 8314.488446] Key type ._llcrypt unregistered [ 8338.355880] Key type ._llcrypt registered [ 8338.358279] Key type .llcrypt registered [ 8338.567862] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8338.580580] alg: No test for adler32 (adler32-zlib) [ 8339.520488] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 8339.660270] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 8341.296251] Key type lgssc registered [ 8342.158334] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8385.533304] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8387.375835] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8397.844643] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8424.316839] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 23:08:54 (1763352534) [ 8481.792325] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8483.886179] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8494.789419] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8520.720728] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 23:10:31 (1763352631) [ 8524.786606] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 23:10:35 (1763352635) [ 8541.966121] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8544.215712] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8554.000703] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8560.633498] Lustre: Mounted lustre-client [ 8568.307375] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [ 8569.588932] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 8570.587752] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 8571.532634] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [ 8575.978889] Lustre: lustre-MDT0000-mdc-ffff9d2b884a2800: Connection to lustre-MDT0000 (at 192.168.204.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8591.265936] Lustre: 222372:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763352686/real 1763352686] req@000000007ac86630 x1849009058621440/t0(0) o400->MGC192.168.204.127@tcp@192.168.204.127@tcp:26/25 lens 224/224 e 0 to 1 dl 1763352702 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 8591.283645] LustreError: MGC192.168.204.127@tcp: Connection to MGS (at 192.168.204.127@tcp) was lost; in progress operations using this service will fail [ 8591.301458] Lustre: Evicted from MGS (at 192.168.204.127@tcp) after server handle changed from 0xa32c87a46e4de537 to 0xa32c87a46e4de7a6 [ 8591.315883] Lustre: MGC192.168.204.127@tcp: Connection restored to 192.168.204.127@tcp (at 192.168.204.127@tcp) [ 8592.404372] Lustre: lustre-MDT0000-mdc-ffff9d2b884a2800: Connection restored to 192.168.204.127@tcp (at 192.168.204.127@tcp) [ 8594.674338] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8595.751652] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 8598.561239] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [ 8601.575290] Lustre: lustre-MDT0000-mdc-ffff9d2b884a2800: Connection to lustre-MDT0000 (at 192.168.204.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8616.935443] LustreError: MGC192.168.204.127@tcp: Connection to MGS (at 192.168.204.127@tcp) was lost; in progress operations using this service will fail [ 8616.946895] Lustre: Evicted from MGS (at 192.168.204.127@tcp) after server handle changed from 0xa32c87a46e4de7a6 to 0xa32c87a46e4deaa8 [ 8616.953616] Lustre: MGC192.168.204.127@tcp: Connection restored to 192.168.204.127@tcp (at 192.168.204.127@tcp) [ 8619.543238] Lustre: lustre-MDT0000-mdc-ffff9d2b884a2800: Connection restored to 192.168.204.127@tcp (at 192.168.204.127@tcp) [ 8621.341987] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8622.172942] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 8624.105903] LustreError: 235193:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b884a2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8624.118994] LustreError: 235193:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8624.151888] Lustre: Unmounted lustre-client [ 8669.560373] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8672.128581] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8683.682606] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8684.081762] Lustre: Mounted lustre-client [ 8689.860769] LustreError: 236814:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b89631800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8689.875252] LustreError: 236814:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8689.877849] LustreError: 236814:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 8689.933409] Lustre: Unmounted lustre-client [ 8710.344684] Key type lgssc unregistered [ 8710.509340] LNet: 237219:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8710.512982] LNetError: 237219:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8710.525910] LNet: Removed LNI 192.168.204.27@tcp [ 8710.878765] Key type .llcrypt unregistered [ 8710.881103] Key type ._llcrypt unregistered [ 8718.328245] Key type ._llcrypt registered [ 8718.329735] Key type .llcrypt registered [ 8719.035481] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 23:13:49 (1763352829) [ 8721.035067] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8721.061510] alg: No test for adler32 (adler32-zlib) [ 8721.967539] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 8722.080916] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 8723.680330] Key type lgssc registered [ 8724.205698] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8747.743169] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8749.598418] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8757.829447] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8764.436085] Lustre: Mounted lustre-client [ 8768.141576] LustreError: 240384:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b868f6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8768.153318] LustreError: 240384:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8768.186804] Lustre: Unmounted lustre-client [ 8803.491668] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8805.226229] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8814.002828] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8819.723325] Lustre: Mounted lustre-client [ 8826.172460] LustreError: 241978:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b913d0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8826.186296] LustreError: 241978:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8826.189417] LustreError: 241978:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 8826.216919] Lustre: Unmounted lustre-client [ 8866.499461] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8868.338098] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8877.825703] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8883.214487] Lustre: Mounted lustre-client [ 8883.890552] LustreError: 243594:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b90a97000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8883.904071] LustreError: 243594:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8883.907279] LustreError: 243594:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 8883.957255] Lustre: Unmounted lustre-client [ 8895.572476] Key type lgssc unregistered [ 8895.701945] LNet: 243994:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8895.705871] LNetError: 243994:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8895.713440] LNet: Removed LNI 192.168.204.27@tcp [ 8895.998856] Key type .llcrypt unregistered [ 8896.001228] Key type ._llcrypt unregistered [ 8902.743060] Key type ._llcrypt registered [ 8902.744704] Key type .llcrypt registered [ 8903.332400] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 23:16:53 (1763353013) [ 8904.781078] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8904.797527] alg: No test for adler32 (adler32-zlib) [ 8905.692116] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 8905.807370] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 8907.408240] Key type lgssc registered [ 8907.893731] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8928.573432] Key type lgssc unregistered [ 8928.716744] LNet: 246302:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8928.720658] LNetError: 246302:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8928.734910] LNet: Removed LNI 192.168.204.27@tcp [ 8929.012838] Key type .llcrypt unregistered [ 8929.014539] Key type ._llcrypt unregistered [ 8941.007754] Key type ._llcrypt registered [ 8941.009458] Key type .llcrypt registered [ 8941.209076] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8941.216860] alg: No test for adler32 (adler32-zlib) [ 8942.100450] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 8942.194504] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 8943.784213] Key type lgssc registered [ 8944.315720] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8980.215488] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8981.965505] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8992.324584] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9014.064392] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [ 9014.900043] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 23:18:45 (1763353125) [ 9028.440852] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9030.097520] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9038.349334] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9048.474371] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 9049.714302] Lustre: Mounted lustre-client [ 9055.206325] Lustre: lustre-OST0001-osc-ffff9d2b98a1c800: Connection to lustre-OST0001 (at 192.168.204.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9058.181673] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 9068.456758] LustreError: 252783:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 9068.475202] Lustre: Unmounted lustre-client [ 9101.629546] Key type lgssc unregistered [ 9101.758852] LNet: 253189:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9101.762497] LNetError: 253189:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9101.773764] LNet: Removed LNI 192.168.204.27@tcp [ 9102.069435] Key type .llcrypt unregistered [ 9102.071324] Key type ._llcrypt unregistered [ 9120.295601] Key type ._llcrypt registered [ 9120.296938] Key type .llcrypt registered [ 9120.462045] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9120.470435] alg: No test for adler32 (adler32-zlib) [ 9121.344453] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 9121.445150] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 9123.040209] Key type lgssc registered [ 9123.521161] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9159.542737] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9161.143376] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9170.720789] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9198.605762] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 23:21:49 (1763353309) [ 9214.839912] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9216.275194] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9228.802551] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 23:22:19 (1763353339) [ 9241.973799] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9243.479781] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9251.682296] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9262.083790] Lustre: Mounted lustre-client [ 9267.185793] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 9273.752134] LustreError: 261010:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9273.780517] Lustre: Unmounted lustre-client [ 9333.654388] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9335.081080] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9368.656742] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9379.331952] Lustre: Mounted lustre-client [ 9379.886804] LustreError: 263074:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b84894000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9379.896309] LustreError: 263074:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9379.898992] LustreError: 263074:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 9379.929961] Lustre: Unmounted lustre-client [ 9390.373153] Key type lgssc unregistered [ 9390.507649] LNet: 263474:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9390.511275] LNetError: 263474:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9390.519820] LNet: Removed LNI 192.168.204.27@tcp [ 9390.806352] Key type .llcrypt unregistered [ 9390.808197] Key type ._llcrypt unregistered [ 9402.228827] Key type ._llcrypt registered [ 9402.247265] Key type .llcrypt registered [ 9402.408914] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9402.417394] alg: No test for adler32 (adler32-zlib) [ 9403.333628] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 9403.451161] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 9405.048316] Key type lgssc registered [ 9405.585318] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9439.666303] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9441.111582] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9449.478494] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9472.158413] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [ 9472.809143] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 23:26:23 (1763353583) [ 9484.225568] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9485.706884] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9492.382106] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9503.732864] Lustre: Mounted lustre-client [ 9504.702498] LustreError: 269506:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2ba02fb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9504.709896] LustreError: 269506:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9504.732157] Lustre: Unmounted lustre-client [ 9530.972539] Key type lgssc unregistered [ 9531.106483] LNet: 269909:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9531.109835] LNetError: 269909:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9531.118727] LNet: Removed LNI 192.168.204.27@tcp [ 9531.442170] Key type .llcrypt unregistered [ 9531.443762] Key type ._llcrypt unregistered [ 9537.455754] Key type ._llcrypt registered [ 9537.457270] Key type .llcrypt registered [ 9538.019680] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [ 9538.702383] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 23:27:29 (1763353649) [ 9542.034608] Key type .llcrypt unregistered [ 9542.036246] Key type ._llcrypt unregistered [ 9545.195310] Key type ._llcrypt registered [ 9545.197025] Key type .llcrypt registered [ 9545.407327] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9545.414869] alg: No test for adler32 (adler32-zlib) [ 9546.307696] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 9546.404674] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 9548.000180] Key type lgssc registered [ 9548.553293] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9569.203243] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9579.511326] Lustre: Mounted lustre-client [ 9580.032954] LustreError: 272920:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2ba020c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9580.039915] LustreError: 272920:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9580.062791] Lustre: Unmounted lustre-client [ 9598.268224] Key type lgssc unregistered [ 9598.410161] LNet: 273321:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9598.414040] LNetError: 273321:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9598.423290] LNet: Removed LNI 192.168.204.27@tcp [ 9598.697378] Key type .llcrypt unregistered [ 9598.698931] Key type ._llcrypt unregistered [ 9604.438459] Key type ._llcrypt registered [ 9604.439798] Key type .llcrypt registered [ 9604.964334] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 23:28:35 (1763353715) [ 9605.728865] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9605.770928] alg: No test for adler32 (adler32-zlib) [ 9606.656442] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 9606.753693] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 9608.336211] Key type lgssc registered [ 9608.763721] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9668.873339] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9679.502853] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9680.774051] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9681.955980] Lustre: Mounted lustre-client [ 9682.476433] LustreError: 276356:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b84430000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9682.486327] LustreError: 276356:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9682.509166] Lustre: Unmounted lustre-client [ 9699.805402] Key type lgssc unregistered [ 9699.920574] LNet: 276757:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9699.924377] LNetError: 276757:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9699.933662] LNet: Removed LNI 192.168.204.27@tcp [ 9700.154729] Key type .llcrypt unregistered [ 9700.155652] Key type ._llcrypt unregistered [ 9705.459374] Key type ._llcrypt registered [ 9705.461013] Key type .llcrypt registered [ 9705.973338] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 23:30:16 (1763353816) [ 9706.783023] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9706.799030] alg: No test for adler32 (adler32-zlib) [ 9707.667582] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 9707.763104] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 9709.352154] Key type lgssc registered [ 9709.740703] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9727.766111] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9729.049000] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9735.746436] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9746.417681] Lustre: Mounted lustre-client [ 9751.524295] Lustre: lustre-MDT0000-mdc-ffff9d2ba0209000: Connection to lustre-MDT0000 (at 192.168.204.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9754.090373] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 9754.101501] LustreError: 279815:0:(file.c:6099:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [ 9754.219282] LustreError: 279834:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2ba0209000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9754.221748] LustreError: MGC192.168.204.127@tcp: Connection to MGS (at 192.168.204.127@tcp) was lost; in progress operations using this service will fail [ 9754.232139] LustreError: 279834:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9754.252121] Lustre: Unmounted lustre-client [ 9765.185315] Key type lgssc unregistered [ 9765.305387] LNet: 280227:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9765.307690] LNetError: 280227:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9765.318273] LNet: Removed LNI 192.168.204.27@tcp [ 9765.547420] Key type .llcrypt unregistered [ 9765.548578] Key type ._llcrypt unregistered [ 9770.997671] Key type ._llcrypt registered [ 9770.999115] Key type .llcrypt registered [ 9771.458393] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 23:31:21 (1763353881) [ 9772.274165] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9772.280729] alg: No test for adler32 (adler32-zlib) [ 9773.145309] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 9773.235829] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 9774.816196] Key type lgssc registered [ 9775.225080] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9793.834724] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9795.034922] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9801.229599] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9811.953482] Lustre: Mounted lustre-client [ 9817.059096] Lustre: lustre-MDT0001-mdc-ffff9d2b913d9800: Connection to lustre-MDT0001 (at 192.168.204.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9828.098616] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 9828.461645] LustreError: 283322:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b913d9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9828.468900] LustreError: 283322:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9828.491810] Lustre: Unmounted lustre-client [ 9839.365510] Key type lgssc unregistered [ 9839.489680] LNet: 283715:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9839.492487] LNetError: 283715:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9839.500190] LNet: Removed LNI 192.168.204.27@tcp [ 9839.755741] Key type .llcrypt unregistered [ 9839.757480] Key type ._llcrypt unregistered [ 9845.263474] Key type ._llcrypt registered [ 9845.264650] Key type .llcrypt registered [ 9845.747789] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 23:32:36 (1763353956) [ 9851.668427] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9851.682471] alg: No test for adler32 (adler32-zlib) [ 9852.550154] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 9852.639165] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 9854.216148] Key type lgssc registered [ 9854.593761] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9893.485399] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 9894.676909] Lustre: Mounted lustre-client [ 9908.224034] LustreError: lustre-MDT0001-mdc-ffff9d2b8c290000: operation mds_statfs to node 192.168.204.127@tcp failed: rc = -107 [ 9908.229377] Lustre: lustre-MDT0001-mdc-ffff9d2b8c290000: Connection to lustre-MDT0001 (at 192.168.204.127@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9915.554800] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 9925.800622] LustreError: 289053:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 9925.820182] Lustre: Unmounted lustre-client [ 9942.852175] Key type lgssc unregistered [ 9942.984860] LNet: 289455:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9942.988149] LNetError: 289455:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9942.997864] LNet: Removed LNI 192.168.204.27@tcp [ 9943.244048] Key type .llcrypt unregistered [ 9943.245527] Key type ._llcrypt unregistered [ 9948.428370] Key type ._llcrypt registered [ 9948.430101] Key type .llcrypt registered [ 9948.878748] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 23:34:19 (1763354059) [ 9949.431185] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [ 9950.048798] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 23:34:20 (1763354060) [ 9950.624423] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [ 9951.206166] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 23:34:21 (1763354061) [ 9951.751506] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [ 9952.317723] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 23:34:22 (1763354062) [ 9952.924786] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [ 9953.483651] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 23:34:24 (1763354064) [ 9954.033050] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [ 9954.564375] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 23:34:25 (1763354065) [ 9957.457766] Key type .llcrypt unregistered [ 9957.459075] Key type ._llcrypt unregistered [ 9959.642458] Key type ._llcrypt registered [ 9959.643554] Key type .llcrypt registered [ 9959.840657] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9959.847113] alg: No test for adler32 (adler32-zlib) [ 9960.703492] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 9960.788114] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [ 9962.368250] Key type lgssc registered [ 9962.845393] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9989.869288] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 9991.193192] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 9999.172564] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10005.465912] Lustre: Mounted lustre-client [10005.949504] LustreError: 295034:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b913da800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10005.957530] LustreError: 295034:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [10005.985211] Lustre: Unmounted lustre-client [10029.261463] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 23:35:39 (1763354139) [10038.956708] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10040.105399] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10046.059925] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10051.571210] Lustre: Mounted lustre-client [10051.888730] LustreError: 297143:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b86754000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10051.895810] LustreError: 297143:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [10051.898424] LustreError: 297143:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [10051.920123] Lustre: Unmounted lustre-client [10070.504274] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 23:36:21 (1763354181) [10098.513197] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10099.803766] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10106.192760] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10109.280397] LNet: 1 local NIs in recovery (showing 1): 192.168.204.27@tcp [10142.169480] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 23:37:32 (1763354252) [10146.114568] Key type lgssc unregistered [10146.238645] LNet: 302580:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10146.241776] LNetError: 302580:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10175.974548] LNet: Removed LNI 192.168.204.27@tcp [10176.221214] Key type .llcrypt unregistered [10176.222949] Key type ._llcrypt unregistered [10311.037907] Key type ._llcrypt registered [10311.038910] Key type .llcrypt registered [10311.202259] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10311.207030] alg: No test for adler32 (adler32-zlib) [10312.066338] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10312.151399] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [10313.736145] Key type lgssc registered [10314.143409] Lustre: Echo OBD driver; http://www.lustre.org/ [10345.160785] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10346.299063] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10353.447250] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10372.247635] Key type lgssc unregistered [10372.369470] LNet: 306178:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10372.372710] LNetError: 306178:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10372.379382] LNet: Removed LNI 192.168.204.27@tcp [10372.613431] Key type .llcrypt unregistered [10372.614933] Key type ._llcrypt unregistered [10396.639135] Key type ._llcrypt registered [10396.640016] Key type .llcrypt registered [10397.083286] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 23:41:47 (1763354507) [10403.709916] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10403.716807] alg: No test for adler32 (adler32-zlib) [10404.558785] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10404.628876] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [10406.208159] Key type lgssc registered [10406.531752] Lustre: Echo OBD driver; http://www.lustre.org/ [10423.835568] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 23:42:14 (1763354534) [10427.756536] Key type lgssc unregistered [10427.860345] LNet: 309720:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10427.862522] LNetError: 309720:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10427.874491] LNet: Removed LNI 192.168.204.27@tcp [10428.101463] Key type .llcrypt unregistered [10428.102946] Key type ._llcrypt unregistered [10431.509385] Key type ._llcrypt registered [10431.510456] Key type .llcrypt registered [10431.664861] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10431.669690] alg: No test for adler32 (adler32-zlib) [10432.518067] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10432.596280] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [10434.176204] Key type lgssc registered [10434.566301] Lustre: Echo OBD driver; http://www.lustre.org/ [10454.157211] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10455.265041] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10462.444920] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10546.651378] LNet: Added LNI 192.168.204.27@tcp50 [8/256/0/180] [10547.440475] LNet: Removed LNI 192.168.204.27@tcp [10548.847064] Lustre: 313990:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff9d2b9093d000: config has no valid NIDs, force dynamic NIDs [10549.902478] Lustre: Mounted lustre-client [10549.930368] LustreError: 314005:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b9093d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10549.937711] LustreError: 314005:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [10549.963173] Lustre: Unmounted lustre-client [10577.345260] Key type lgssc unregistered [10577.483522] LNet: 314845:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10577.485852] LNetError: 314845:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10577.502465] LNet: Removed LNI 192.168.204.27@tcp50 [10577.706205] Key type .llcrypt unregistered [10577.707214] Key type ._llcrypt unregistered [10614.083772] Key type ._llcrypt registered [10614.084788] Key type .llcrypt registered [10614.586701] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 23:45:25 (1763354725) [10615.295439] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10615.324085] alg: No test for adler32 (adler32-zlib) [10616.174161] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10616.252770] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [10617.840213] Key type lgssc registered [10618.261180] Lustre: Echo OBD driver; http://www.lustre.org/ [10634.803938] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10635.911202] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10641.680245] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10642.817196] Lustre: Mounted lustre-client [10662.488385] Lustre: Mounted fs15246-client [10667.247668] LustreError: 318966:0:(lov_obd.c:783:lov_cleanup()) fs15246-clilov-ffff9d2b884a0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10667.254389] LustreError: 318966:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [10667.266180] Lustre: Unmounted fs15246-client [10679.564084] LustreError: 319183:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d2b879fb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10679.567658] LustreError: 319183:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [10679.569175] LustreError: 319183:0:(obd_class.h:479:obd_check_dev()) Skipped 3 previous similar messages [10679.588117] Lustre: Unmounted lustre-client [10697.035274] Key type lgssc unregistered [10697.162340] LNet: 319585:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10697.165940] LNetError: 319585:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10697.174780] LNet: Removed LNI 192.168.204.27@tcp [10697.446526] Key type .llcrypt unregistered [10697.448057] Key type ._llcrypt unregistered [10701.322908] Key type ._llcrypt registered [10701.324281] Key type .llcrypt registered [10701.816944] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 23:46:52 (1763354812) [10702.326710] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10702.360100] alg: No test for adler32 (adler32-zlib) [10703.239671] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10703.323416] LNet: Added LNI 192.168.204.27@tcp [8/256/0/180] [10704.904173] Key type lgssc registered [10705.297450] Lustre: Echo OBD driver; http://www.lustre.org/ [10750.463973] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [10751.582955] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [10758.943410] Lustre: DEBUG MARKER: oleg427-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [10811.170038] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 10569 sec ========= 23:48:41 (1763354921) [10811.726114] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 23:48:42 (1763354922) === [10812.982457] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 23:48:43 (1763354923) === [10824.669299] Key type lgssc unregistered [10824.785383] LNet: 328719:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10824.787782] LNetError: 328719:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10824.794564] LNet: Removed LNI 192.168.204.27@tcp [10825.049406] Key type .llcrypt unregistered [10825.051278] Key type ._llcrypt unregistered