[ 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 393393706 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.001010] APIC: Switch to symmetric I/O mode setup [ 0.002241] x2apic enabled [ 0.003004] Switched APIC routing to physical x2apic. [ 0.004007] kvm-guest: setup PV IPIs [ 0.006577] ..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.007013] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.008006] pid_max: default: 32768 minimum: 301 [ 0.009106] LSM: Security Framework initializing [ 0.010026] Yama: becoming mindful. [ 0.011022] SELinux: Initializing. [ 0.012048] *** VALIDATE selinux *** [ 0.019630] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.023383] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.025049] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.026064] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.027064] *** VALIDATE tmpfs *** [ 0.029130] *** VALIDATE proc *** [ 0.030171] *** VALIDATE cgroup *** [ 0.031005] *** VALIDATE cgroup2 *** [ 0.032194] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.033105] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.034003] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.035020] Spectre V2 : User space: Vulnerable [ 0.036004] Speculative Store Bypass: Vulnerable [ 0.038360] debug: unmapping init [mem 0xffffffff9cc59000-0xffffffff9cc60fff] [ 0.040000] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.040407] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.041013] ... version: 2 [ 0.042007] ... bit width: 48 [ 0.043006] ... generic registers: 4 [ 0.044007] ... value mask: 0000ffffffffffff [ 0.045007] ... max period: 00007fffffffffff [ 0.046008] ... fixed-purpose events: 3 [ 0.047007] ... event mask: 000000070000000f [ 0.048141] rcu: Hierarchical SRCU implementation. [ 0.050041] smp: Bringing up secondary CPUs ... [ 0.051340] x86: Booting SMP configuration: [ 0.052017] .... node #0, CPUs: #1 #2 #3 [ 0.054775] smp: Brought up 1 node, 4 CPUs [ 0.056007] smpboot: Max logical packages: 1 [ 0.057010] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.278474] node 0 deferred pages initialised in 220ms [ 0.281238] devtmpfs: initialized [ 0.282266] x86/mm: Memory block size: 128MB [ 0.285107] gcov: version magic: 0x41383552 [ 0.287340] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.288063] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.289230] pinctrl core: initialized pinctrl subsystem [ 0.290117] [ 0.290440] ************************************************************* [ 0.291006] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.292005] ** ** [ 0.293005] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.294005] ** ** [ 0.295005] ** This means that this kernel is built to expose internal ** [ 0.296005] ** IOMMU data structures, which may compromise security on ** [ 0.297005] ** your system. ** [ 0.298005] ** ** [ 0.299005] ** If you see this message and you are not debugging the ** [ 0.300005] ** kernel, report this immediately to your vendor! ** [ 0.301005] ** ** [ 0.302005] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.303006] ************************************************************* [ 0.304536] NET: Registered protocol family 16 [ 0.305337] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.306028] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.307027] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.308320] cpuidle: using governor menu [ 0.310595] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.312523] PCI: Using configuration type 1 for base access [ 0.315127] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.321083] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.322010] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.323117] cryptd: max_cpu_qlen set to 1000 [ 0.325789] ACPI: Added _OSI(Module Device) [ 0.326010] ACPI: Added _OSI(Processor Device) [ 0.326949] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.328007] ACPI: Added _OSI(Processor Aggregator Device) [ 0.330754] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.335269] ACPI: Interpreter enabled [ 0.335986] ACPI: PM: (supports S0 S3 S4 S5) [ 0.337010] ACPI: Using IOAPIC for interrupt routing [ 0.338092] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.340270] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.347118] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.349019] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.350007] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.352064] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.355949] acpiphp: Slot [2] registered [ 0.356095] acpiphp: Slot [3] registered [ 0.356891] acpiphp: Slot [4] registered [ 0.358052] acpiphp: Slot [5] registered [ 0.358964] acpiphp: Slot [6] registered [ 0.360059] acpiphp: Slot [7] registered [ 0.360894] acpiphp: Slot [8] registered [ 0.361052] acpiphp: Slot [9] registered [ 0.361982] acpiphp: Slot [10] registered [ 0.363054] acpiphp: Slot [11] registered [ 0.363947] acpiphp: Slot [12] registered [ 0.365044] acpiphp: Slot [13] registered [ 0.365894] acpiphp: Slot [14] registered [ 0.366056] acpiphp: Slot [15] registered [ 0.366849] acpiphp: Slot [16] registered [ 0.368043] acpiphp: Slot [17] registered [ 0.368944] acpiphp: Slot [18] registered [ 0.370044] acpiphp: Slot [19] registered [ 0.370869] acpiphp: Slot [20] registered [ 0.371052] acpiphp: Slot [21] registered [ 0.371901] acpiphp: Slot [22] registered [ 0.373064] acpiphp: Slot [23] registered [ 0.374043] acpiphp: Slot [24] registered [ 0.374962] acpiphp: Slot [25] registered [ 0.376043] acpiphp: Slot [26] registered [ 0.376926] acpiphp: Slot [27] registered [ 0.378043] acpiphp: Slot [28] registered [ 0.378896] acpiphp: Slot [29] registered [ 0.380078] acpiphp: Slot [30] registered [ 0.381095] acpiphp: Slot [31] registered [ 0.383039] PCI host bridge to bus 0000:00 [ 0.383986] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.385008] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.386007] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.388008] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.389007] pci_bus 0000:00: root bus resource [mem 0x180000000-0x1ffffffff window] [ 0.391009] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.392114] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.394678] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.397715] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.402359] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.404939] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.406008] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.408007] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.409006] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.410404] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.412491] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.414019] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.415366] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.418007] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.424454] pci 0000:00:02.0: reg 0x20: [mem 0xfebf4000-0xfebf7fff 64bit pref] [ 0.426866] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.430259] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.434009] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.445005] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.452012] pci 0000:00:05.0: reg 0x20: [mem 0xfebf8000-0xfebfbfff 64bit pref] [ 0.458970] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.462008] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.465024] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.473031] pci 0000:00:06.0: reg 0x20: [mem 0xfebfc000-0xfebfffff 64bit pref] [ 0.479158] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.481214] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.482207] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.483364] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.485325] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.491119] iommu: Default domain type: Passthrough [ 0.492324] SCSI subsystem initialized [ 0.494118] ACPI: bus type USB registered [ 0.496113] usbcore: registered new interface driver usbfs [ 0.498062] usbcore: registered new interface driver hub [ 0.500056] usbcore: registered new device driver usb [ 0.501081] pps_core: LinuxPPS API ver. 1 registered [ 0.503007] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.504019] PTP clock support registered [ 0.506041] EDAC MC: Ver: 3.0.0 [ 0.507423] PCI: Using ACPI for IRQ routing [ 0.508545] NetLabel: Initializing [ 0.510008] NetLabel: domain hash size = 128 [ 0.510996] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.512057] NetLabel: unlabeled traffic allowed by default [ 0.513110] vgaarb: loaded [ 0.514262] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.515006] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.520318] clocksource: Switched to clocksource kvm-clock [ 0.636281] VFS: Disk quotas dquot_6.6.0 [ 0.637752] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.640114] *** VALIDATE ramfs *** [ 0.641362] *** VALIDATE hugetlbfs *** [ 0.643410] pnp: PnP ACPI init [ 0.645895] pnp: PnP ACPI: found 6 devices [ 0.660274] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.663715] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.665723] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.667684] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.669851] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.672066] pci_bus 0000:00: resource 8 [mem 0x180000000-0x1ffffffff window] [ 0.674523] NET: Registered protocol family 2 [ 0.676735] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.682046] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.686586] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.692911] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.697187] TCP: Hash tables configured (established 65536 bind 65536) [ 0.700917] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.703799] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.706393] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.709181] NET: Registered protocol family 1 [ 0.711251] RPC: Registered named UNIX socket transport module. [ 0.712511] RPC: Registered udp transport module. [ 0.713397] RPC: Registered tcp transport module. [ 0.714264] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.715513] NET: Registered protocol family 44 [ 0.716493] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.717605] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.718660] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.720028] PCI: CLS 0 bytes, default 64 [ 0.721094] Unpacking initramfs... [ 1.986719] debug: unmapping init [mem 0xffff9bd9fcc64000-0xffff9bd9fffcffff] [ 1.990742] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 1.993555] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 1.996812] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.469741] Initialise system trusted keyrings [ 2.471017] Key type blacklist registered [ 2.472224] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.478650] zbud: loaded [ 2.480763] *** VALIDATE nfs *** [ 2.481586] *** VALIDATE nfs4 *** [ 2.482550] pstore: using deflate compression [ 2.484475] Platform Keyring initialized [ 2.555304] NET: Registered protocol family 38 [ 2.557080] Key type asymmetric registered [ 2.557972] Asymmetric key parser 'x509' registered [ 2.559078] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.561231] io scheduler mq-deadline registered [ 2.562262] io scheduler kyber registered [ 2.563380] io scheduler bfq registered [ 2.565032] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.567486] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.569196] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.571245] ACPI: Power Button [PWRF] [ 2.643736] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.715552] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.824503] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.856861] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.889508] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.894566] Non-volatile memory driver v1.3 [ 2.896409] Linux agpgart interface v0.103 [ 2.925823] virtio_blk virtio1: [vda] 133544 512-byte logical blocks (68.4 MB/65.2 MiB) [ 2.928779] vda: detected capacity change from 0 to 68374528 [ 2.943157] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.946458] vdb: detected capacity change from 0 to 1073741824 [ 2.952639] libphy: Fixed MDIO Bus: probed [ 2.957295] usbcore: registered new interface driver usbserial_generic [ 2.959318] usbserial: USB Serial support registered for generic [ 2.962129] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.965220] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.966364] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.967881] mousedev: PS/2 mouse device common for all mice [ 2.969691] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.972090] rtc_cmos 00:05: RTC can wake from S4 [ 2.974198] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 2.976486] rtc_cmos 00:05: registered as rtc0 [ 2.978763] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 2.979122] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.984072] intel_pstate: CPU model not supported [ 2.986801] hid: raw HID events driver (C) Jiri Kosina [ 2.988702] usbcore: registered new interface driver usbhid [ 2.990725] usbhid: USB HID core driver [ 2.992487] drop_monitor: Initializing network drop monitor service [ 2.994957] Initializing XFRM netlink socket [ 2.997064] NET: Registered protocol family 10 [ 2.999822] Segment Routing with IPv6 [ 3.001354] NET: Registered protocol family 17 [ 3.003349] mpls_gso: MPLS GSO support [ 3.007982] RAS: Correctable Errors collector initialized. [ 3.010214] AVX version of gcm_enc/dec engaged. [ 3.011575] AES CTR mode by8 optimization enabled [ 3.075889] sched_clock: Marking stable (3075864807, 0)->(3798913692, -723048885) [ 3.079259] registered taskstats version 1 [ 3.081289] Loading compiled-in X.509 certificates [ 3.083067] zswap: loaded using pool lzo/zbud [ 3.106381] Key type big_key registered [ 3.118626] Key type encrypted registered [ 3.119676] ima: No TPM chip found, activating TPM-bypass! [ 3.121147] ima: Allocated hash algorithm: sha1 [ 3.122175] ima: No architecture policies found [ 3.123786] evm: Initialising EVM extended attributes: [ 3.124908] evm: security.selinux [ 3.125629] evm: security.ima [ 3.126377] evm: security.capability [ 3.127789] evm: HMAC attrs: 0x1 [ 3.130136] rtc_cmos 00:05: setting system clock to 2025-08-12 22:56:06 UTC (1755039366) [ 3.134984] debug: unmapping init [mem 0xffffffff9dc03000-0xffffffff9ddfffff] [ 3.137147] debug: unmapping init [mem 0xffffffff9c982000-0xffffffff9cc58fff] [ 3.144068] Write protecting the kernel read-only data: 28672k [ 3.147769] debug: unmapping init [mem 0xffffffff9b003000-0xffffffff9b1fffff] [ 3.150524] debug: unmapping init [mem 0xffffffff9b914000-0xffffffff9b9fffff] [ 3.180696] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 3.197442] systemd[1]: Detected virtualization kvm. [ 3.199119] systemd[1]: Detected architecture x86-64. [ 3.200727] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.224299] systemd[1]: No hostname configured. [ 3.226191] systemd[1]: Set hostname to . [ 3.228324] random: systemd: uninitialized urandom read (16 bytes read) [ 3.230762] systemd[1]: Initializing machine ID from random generator. [ 3.289112] random: ln: uninitialized urandom read (6 bytes read) [ 3.368844] random: systemd: uninitialized urandom read (16 bytes read) [ 3.371651] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 3.375943] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 3.379616] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Local File Systems. [ OK ] Reached target Slices. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Timers. [ OK ] Listening on Journal Socket. Starting Create list of required st…ce nodes for the current kernel... Starting Apply Kernel Variables... Starting Setup Virtual Console... [ OK ] Reached target Sockets. [ OK ] Started Memstrack Anylazing Service. Starting Journal Service... Starting Create Volatile Files and Directories... [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Started Setup Virtual Console. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 3.999107] device-mapper: uevent: version 1.0.3 [ 4.000861] 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... [ 4.820982] random: fast init done [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 5.068472] virtio_net virtio0 ens2: renamed from eth0 [ 5.256793] scsi host0: ata_piix [ 5.304919] scsi host1: ata_piix [ 5.306190] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.316200] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 5.844047] hrtimer: interrupt took 2526821 ns [ 9.645198] random: crng init done [ 9.646294] random: 7 urandom warning(s) missed due to ratelimiting [ 10.402755] dracut-initqueue[594]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 11.207449] 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. Stopping Hardware RNG Entropy Gatherer Daemon... [ 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). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. Stopping udev Kernel Device Manager... [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Swap. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 12.827479] printk: systemd: 26 output lines suppressed due to ratelimiting [ 13.281334] SELinux: Disabled at runtime. [ 13.350429] 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) [ 13.361684] systemd[1]: Detected virtualization kvm. [ 13.363407] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 14.239966] systemd[1]: initrd-switch-root.service: Succeeded. [ 14.243182] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 14.249321] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 14.255426] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 14.259413] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 14.286127] systemd[1]: Starting Journal Service... Starting Journal Service... [ 14.302852] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Listening on udev Kernel Socket. Mounting Kernel Debug File System... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target rpc_pipefs.target. Starting Remount Root and Kernel File Systems... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Created slice system-serial\x2dgetty.slice. Mounting Huge Pages File System... [ OK ] Stopped target Switch Root. [ OK ] Reached target Paths. [ OK ] Created slice User and Session Slice. [ OK ] Listening on Process Core Dump Socket. [ OK ] Listening on initctl Compatibility Named Pipe. Activating swap /dev/disk/by-label/SWAP... [ 14.570791] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Reached target Slices. [ OK ] Created slice system-sshd\x2dkeygen.slice. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Created slice system-getty.slice. [ OK ] Stopped target Initrd File Systems. Starting Apply Kernel Variables... [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Reached target Local Encrypted Volumes. Mounting POSIX Message Queue File System... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Stopped target Initrd Root File System. [ OK ] Started Journal Service. [ OK ] Mounted Kernel Debug File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Huge Pages File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 15.149801] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 15.884287] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 15.911212] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 16.122966] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 16.139114] EDAC sbridge: Ver: 1.1.2 [ 17.682159] Key type dns_resolver registered [ 18.020303] NFS: Registering the id_resolver key type [ 18.022372] Key type id_resolver registered [ 18.025170] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... Starting Mark the need to relabel after reboot... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... Starting Login Service... [ OK ] Started irqbalance daemon. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target sshd-keygen.target. [ OK ] Started dnf makecache --timer. Starting Restore /run/initramfs on shutdown... [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Started Login Service. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting System Logging Service... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg445-client login: [ 47.121685] libcfs: loading out-of-tree module taints kernel. [ 47.152153] Key type ._llcrypt registered [ 47.169088] Key type .llcrypt registered [ 47.461119] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 47.466582] alg: No test for adler32 (adler32-zlib) [ 48.424708] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 48.697322] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 48.698897] LNet: Accept secure, port 988 [ 50.303212] Key type lgssc registered [ 50.816774] Lustre: Echo OBD driver; http://www.lustre.org/ [ 109.618624] Lustre: Mounted lustre-client [ 111.968502] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 122.613988] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing check_logdir /tmp/testlogs/ [ 124.026702] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing yml_node [ 125.530865] Lustre: DEBUG MARKER: Client: 2.16.56.30 [ 126.320334] Lustre: DEBUG MARKER: MDS: 2.16.56.30 [ 127.184390] Lustre: DEBUG MARKER: OSS: 2.16.56.30 [ 127.734744] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Tue Aug 12 18:58:10 EDT 2025 [ 133.593231] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 134.101935] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 134.645528] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 144.932319] LustreError: 8975:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 144.945809] Lustre: Unmounted lustre-client [ 222.097985] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 223.436389] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 231.149220] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 251.218159] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 19:00:14 (1755039614) [ 252.021086] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 252.650669] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 19:00:15 (1755039615) [ 262.392660] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 263.549437] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 269.442671] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 285.172740] Lustre: Mounted lustre-client [ 286.922981] LustreError: 14400:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda46008800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 286.929490] LustreError: 14400:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 286.931129] LustreError: 14400:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 286.952085] Lustre: Unmounted lustre-client [ 313.290743] Key type lgssc unregistered [ 313.424614] LNet: 14823:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 314.470619] LNet: Removed LNI 192.168.204.45@tcp [ 314.802987] Key type .llcrypt unregistered [ 314.804483] Key type ._llcrypt unregistered [ 321.787162] Key type ._llcrypt registered [ 321.788905] Key type .llcrypt registered [ 322.334496] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 19:01:25 (1755039685) [ 322.873368] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 322.890507] alg: No test for adler32 (adler32-zlib) [ 323.775270] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 323.881755] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 323.884936] LNet: Accept secure, port 988 [ 325.495212] Key type lgssc registered [ 325.906072] Lustre: Echo OBD driver; http://www.lustre.org/ [ 362.968262] Lustre: Mounted lustre-client [ 444.529554] LustreError: 18139:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda503da000: lov tgt 1990 not cleaned! deathrow=0, lovrc=1 [ 444.535161] LustreError: 18139:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 444.551965] Lustre: Unmounted lustre-client [ 467.363825] Key type lgssc unregistered [ 467.474411] LNet: 18604:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 468.517631] LNet: Removed LNI 192.168.204.45@tcp [ 468.758304] Key type .llcrypt unregistered [ 468.759357] Key type ._llcrypt unregistered [ 473.403322] Key type ._llcrypt registered [ 473.404278] Key type .llcrypt registered [ 473.856563] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 19:03:56 (1755039836) [ 474.649593] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 474.654902] alg: No test for adler32 (adler32-zlib) [ 475.514402] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 475.603232] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 475.604648] LNet: Accept secure, port 988 [ 477.191149] Key type lgssc registered [ 477.617592] Lustre: Echo OBD driver; http://www.lustre.org/ [ 495.289701] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 496.492358] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 503.133260] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 513.525173] Lustre: Mounted lustre-client [ 517.571223] LustreError: 21698:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda467fb800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 517.577830] LustreError: 21698:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 517.594899] Lustre: Unmounted lustre-client [ 535.439901] Key type lgssc unregistered [ 535.560508] LNet: 22100:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 536.614574] LNet: Removed LNI 192.168.204.45@tcp [ 536.927776] Key type .llcrypt unregistered [ 536.929567] Key type ._llcrypt unregistered [ 541.449042] Key type ._llcrypt registered [ 541.450409] Key type .llcrypt registered [ 541.924304] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 19:05:04 (1755039904) [ 542.700586] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 542.745417] alg: No test for adler32 (adler32-zlib) [ 543.619088] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 543.703109] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 543.706368] LNet: Accept secure, port 988 [ 545.295194] Key type lgssc registered [ 545.706379] Lustre: Echo OBD driver; http://www.lustre.org/ [ 562.922993] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 564.198845] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 570.384047] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 581.115198] Lustre: Mounted lustre-client [ 584.085469] LustreError: 24786:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda589a7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 584.091782] LustreError: 24786:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 584.117637] Lustre: Unmounted lustre-client [ 593.659179] Key type lgssc unregistered [ 593.788617] LNet: 25187:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 594.854133] LNet: Removed LNI 192.168.204.45@tcp [ 595.107561] Key type .llcrypt unregistered [ 595.108825] Key type ._llcrypt unregistered [ 601.320360] Key type ._llcrypt registered [ 601.321376] Key type .llcrypt registered [ 601.801408] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 19:06:04 (1755039964) [ 602.750238] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 602.755712] alg: No test for adler32 (adler32-zlib) [ 603.635390] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 603.730392] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 603.732033] LNet: Accept secure, port 988 [ 605.311261] Key type lgssc registered [ 605.759758] Lustre: Echo OBD driver; http://www.lustre.org/ [ 623.044480] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 624.204947] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 630.618370] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 641.015123] Lustre: Mounted lustre-client [ 646.116026] Lustre: lustre-MDT0000-mdc-ffff9bda60079800: Connection to lustre-MDT0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 651.234323] Lustre: lustre-MDT0001-mdc-ffff9bda60079800: Connection to lustre-MDT0001 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 657.525378] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 667.615150] Lustre: 26143:0:(client.c:2453:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1755040014/real 1755040014] req@0000000012953d03 x1840292788443264/t0(0) o400->MGC192.168.204.145@tcp@192.168.204.145@tcp:26/25 lens 224/224 e 0 to 1 dl 1755040030 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 667.627929] LustreError: MGC192.168.204.145@tcp: Connection to MGS (at 192.168.204.145@tcp) was lost; in progress operations using this service will fail [ 667.820290] LustreError: 28351:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 667.834483] Lustre: Unmounted lustre-client [ 678.743027] Key type lgssc unregistered [ 678.863309] LNet: 28742:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 679.909324] LNet: Removed LNI 192.168.204.45@tcp [ 680.153692] Key type .llcrypt unregistered [ 680.154851] Key type ._llcrypt unregistered [ 686.340494] Key type ._llcrypt registered [ 686.341399] Key type .llcrypt registered [ 686.794659] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 19:07:29 (1755040049) [ 687.473258] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 687.492034] alg: No test for adler32 (adler32-zlib) [ 688.344650] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 688.422454] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 688.424385] LNet: Accept secure, port 988 [ 690.007261] Key type lgssc registered [ 690.389537] Lustre: Echo OBD driver; http://www.lustre.org/ [ 708.027247] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 709.240580] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 715.214114] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 726.001672] Lustre: Mounted lustre-client [ 731.106940] Lustre: lustre-OST0000-osc-ffff9bda46d92000: Connection to lustre-OST0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 743.495702] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 753.768630] LustreError: 31854:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 753.783129] Lustre: Unmounted lustre-client [ 770.743365] Key type lgssc unregistered [ 770.864447] LNet: 32254:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 771.877524] LNet: Removed LNI 192.168.204.45@tcp [ 772.129780] Key type .llcrypt unregistered [ 772.130857] Key type ._llcrypt unregistered [ 778.013208] Key type ._llcrypt registered [ 778.014332] Key type .llcrypt registered [ 778.431389] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 19:09:01 (1755040141) [ 779.082519] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 779.115070] alg: No test for adler32 (adler32-zlib) [ 779.972617] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 780.051261] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 780.052870] LNet: Accept secure, port 988 [ 781.639150] Key type lgssc registered [ 781.985423] Lustre: Echo OBD driver; http://www.lustre.org/ [ 798.157514] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 799.293640] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 806.735276] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 817.136170] Lustre: Mounted lustre-client [ 819.105672] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 820.418564] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 821.540887] LNetError: Refusing connection from 192.168.204.45 for 127.0.0.2@tcp: No matching NI [ 821.542984] LNetError: 33199:0:(socklnd_cb.c:1794:ksocknal_recv_hello()) Error -104 reading HELLO from 127.0.0.2 [ 821.546620] LNetError: Connection to 127.0.0.2@tcp at host 127.0.0.2:988 was reset: is it running a compatible version of Lustre and is 127.0.0.2@tcp one of its NIDs? [ 830.931968] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2025-08-1219h09m53s [ 831.590593] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 832.482896] Lustre: lustre-MDT0000-mdc-ffff9bda4600b000: Connection to lustre-MDT0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 832.492915] LNetError: Refusing connection from 192.168.204.45 for 127.0.0.2@tcp: No matching NI [ 832.495095] LNetError: 33200:0:(socklnd_cb.c:1794:ksocknal_recv_hello()) Error -104 reading HELLO from 127.0.0.2 [ 832.499439] LNetError: Connection to 127.0.0.2@tcp at host 127.0.0.2:988 was reset: is it running a compatible version of Lustre and is 127.0.0.2@tcp one of its NIDs? [ 838.623741] LNetError: Refusing connection from 192.168.204.45 for 127.0.0.2@tcp: No matching NI [ 838.626561] LNetError: 33201:0:(socklnd_cb.c:1794:ksocknal_recv_hello()) Error -104 reading HELLO from 127.0.0.2 [ 838.629180] LNetError: Connection to 127.0.0.2@tcp at host 127.0.0.2:988 was reset: is it running a compatible version of Lustre and is 127.0.0.2@tcp one of its NIDs? [ 843.680139] LNetError: Refusing connection from 192.168.204.45 for 127.0.0.2@tcp: No matching NI [ 843.682974] LNetError: 33198:0:(socklnd_cb.c:1794:ksocknal_recv_hello()) Error -104 reading HELLO from 127.0.0.2 [ 843.687610] LNetError: Connection to 127.0.0.2@tcp at host 127.0.0.2:988 was reset: is it running a compatible version of Lustre and is 127.0.0.2@tcp one of its NIDs? [ 847.482921] LustreError: 35947:0:(lmv_obd.c:1468:lmv_statfs()) lustre-MDT0000-mdc-ffff9bda4600b000: can't stat MDS #0: rc = -19 [ 847.968733] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 850.912833] LustreError: MGC192.168.204.145@tcp: Connection to MGS (at 192.168.204.145@tcp) was lost; in progress operations using this service will fail [ 850.915449] LustreError: lustre-MDT0000-mdc-ffff9bda4600b000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 850.920167] Lustre: Evicted from MGS (at 192.168.204.145@tcp) after server handle changed from 0xac1f2efdaf474338 to 0xac1f2efdaf4746b8 [ 850.920992] Lustre: lustre-MDT0000-mdc-ffff9bda4600b000: Connection restored to 192.168.204.145@tcp (at 192.168.204.145@tcp) [ 850.924965] Lustre: Skipped 1 previous similar message [ 852.857090] Lustre: DEBUG MARKER: Wait for df (35947) ... [ 853.611808] Lustre: DEBUG MARKER: done [ 853.958160] LustreError: 36555:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda4600b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 853.962449] LustreError: 36555:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 853.986691] Lustre: Unmounted lustre-client [ 869.481243] Key type lgssc unregistered [ 869.609554] LNet: 36956:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 870.633702] LNet: Removed LNI 192.168.204.45@tcp [ 870.886757] Key type .llcrypt unregistered [ 870.888728] Key type ._llcrypt unregistered [ 886.395561] Key type ._llcrypt registered [ 886.396744] Key type .llcrypt registered [ 886.587247] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 886.593414] alg: No test for adler32 (adler32-zlib) [ 887.449111] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 887.534351] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 887.536167] LNet: Accept secure, port 988 [ 889.127139] Key type lgssc registered [ 889.506714] Lustre: Echo OBD driver; http://www.lustre.org/ [ 919.789835] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 920.974994] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 928.568373] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 953.970841] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 19:11:56 (1755040316) [ 964.944980] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 966.225212] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 973.695436] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 984.048559] Lustre: Mounted lustre-client [ 986.440704] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2025-08-1219h12m29s [ 986.960342] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 989.424803] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 990.235840] Lustre: DEBUG MARKER: Creating a test file and stat it [ 997.355509] LNetError: Refusing connection from 192.168.204.45 for 127.0.0.2@tcp: No matching NI [ 997.362555] LNetError: 37723:0:(socklnd_cb.c:1794:ksocknal_recv_hello()) Error -104 reading HELLO from 127.0.0.2 [ 997.368200] LNetError: Connection to 127.0.0.2@tcp at host 127.0.0.2:988 was reset: is it running a compatible version of Lustre and is 127.0.0.2@tcp one of its NIDs? [ 1010.655215] Lustre: 43804:0:(client.c:2453:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1755040353/real 1755040353] req@0000000041df6f89 x1840293085189504/t0(0) o101->lustre-MDT0000-mdc-ffff9bda467fa000@192.168.204.145@tcp:12/10 lens 576/1152 e 0 to 1 dl 1755040373 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:0 [ 1010.666706] Lustre: lustre-MDT0000-mdc-ffff9bda467fa000: Connection to lustre-MDT0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1010.678072] Lustre: lustre-MDT0000-mdc-ffff9bda467fa000: Connection restored to 192.168.204.145@tcp (at 192.168.204.145@tcp) [ 1030.784866] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1031.772535] Lustre: DEBUG MARKER: done [ 1032.722402] LustreError: 44280:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda467fa000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1032.731383] LustreError: 44280:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1032.759049] Lustre: Unmounted lustre-client [ 1050.365421] Key type lgssc unregistered [ 1050.484522] LNet: 44682:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1051.558764] LNet: Removed LNI 192.168.204.45@tcp [ 1051.872300] Key type .llcrypt unregistered [ 1051.873534] Key type ._llcrypt unregistered [ 1068.813585] Key type ._llcrypt registered [ 1068.814787] Key type .llcrypt registered [ 1069.035882] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1069.041350] alg: No test for adler32 (adler32-zlib) [ 1069.897278] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 1069.984439] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 1069.986601] LNet: Accept secure, port 988 [ 1071.575176] Key type lgssc registered [ 1071.973118] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1104.966248] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1106.130287] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1114.548953] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1137.145204] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 19:14:59 (1755040499) [ 1137.695529] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1138.291473] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 19:15:01 (1755040501) [ 1148.296169] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 19:15:10 (1755040510) [ 1159.514784] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1161.099899] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1168.091378] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1178.619967] Lustre: Mounted lustre-client [ 1180.730137] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 1181.770408] LustreError: 52491:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda467f8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1181.779949] LustreError: 52491:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1181.805122] Lustre: Unmounted lustre-client [ 1191.245121] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 1207.827603] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1209.047436] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1209.218488] Lustre: Mounted lustre-client [ 1210.108405] LustreError: 53748:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda465f9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1210.119530] LustreError: 53748:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1210.122716] LustreError: 53748:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 1210.147241] Lustre: Unmounted lustre-client [ 1236.393271] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1237.503468] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1243.122899] Lustre: Mounted lustre-client [ 1243.987684] LustreError: 54869:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda49143800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1243.996220] LustreError: 54869:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1243.997763] LustreError: 54869:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 1244.020114] Lustre: Unmounted lustre-client [ 1255.223673] Lustre: DEBUG MARKER: files compared the same [ 1269.421881] Key type lgssc unregistered [ 1269.537641] LNet: 55573:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1270.565859] LNet: Removed LNI 192.168.204.45@tcp [ 1270.797900] Key type .llcrypt unregistered [ 1270.798903] Key type ._llcrypt unregistered [ 1276.440169] Key type ._llcrypt registered [ 1276.441407] Key type .llcrypt registered [ 1276.892775] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 19:17:19 (1755040639) [ 1277.637751] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1277.654872] alg: No test for adler32 (adler32-zlib) [ 1278.501079] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 1278.575430] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 1278.577092] LNet: Accept secure, port 988 [ 1280.159170] Key type lgssc registered [ 1280.530106] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1296.884508] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1298.094908] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1304.023223] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1319.919196] Lustre: Mounted lustre-client [ 1321.575230] LustreError: 58654:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda45520800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1321.581699] LustreError: 58654:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1321.601996] Lustre: Unmounted lustre-client [ 1339.796069] Key type lgssc unregistered [ 1339.933617] LNet: 59056:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1340.966455] LNet: Removed LNI 192.168.204.45@tcp [ 1341.291104] Key type .llcrypt unregistered [ 1341.292150] Key type ._llcrypt unregistered [ 1347.290806] Key type ._llcrypt registered [ 1347.291704] Key type .llcrypt registered [ 1347.714479] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 19:18:30 (1755040710) [ 1348.469133] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1348.495409] alg: No test for adler32 (adler32-zlib) [ 1349.353150] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 1349.428864] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 1349.430526] LNet: Accept secure, port 988 [ 1351.007200] Key type lgssc registered [ 1351.561606] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1416.790697] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1426.665101] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1427.839482] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1440.779092] Key type lgssc unregistered [ 1440.892299] LNet: 62435:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1440.900298] LNet: Removed LNI 192.168.204.45@tcp [ 1441.149582] Key type .llcrypt unregistered [ 1441.151456] Key type ._llcrypt unregistered [ 1446.827369] Key type ._llcrypt registered [ 1446.828310] Key type .llcrypt registered [ 1447.257677] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 19:20:10 (1755040810) [ 1448.393224] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1448.413381] alg: No test for adler32 (adler32-zlib) [ 1449.274907] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 1449.361882] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 1449.364184] LNet: Accept secure, port 988 [ 1450.951261] Key type lgssc registered [ 1451.350525] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1476.274287] Lustre: Mounted lustre-client [ 1481.595487] LustreError: 64924:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda503dc000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1481.601378] LustreError: 64924:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1481.620275] Lustre: Unmounted lustre-client [ 1501.880179] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 19:21:04 (1755040864) [ 1541.723486] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1547.601499] Lustre: Mounted lustre-client [ 1552.939867] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1552.942143] LustreError: 68869:0:(file.c:248:ll_close_inode_openhandle()) lustre-clilmv-ffff9bda458e5000: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 1552.949215] Lustre: 63428:0:(llite_lib.c:4214:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.204.145@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 1563.241646] LustreError: 68869:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 1563.244462] LustreError: 68869:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 1563.262752] Lustre: Unmounted lustre-client [ 1590.155446] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 19:22:32 (1755040952) [ 1594.400709] Key type lgssc unregistered [ 1594.551463] LNet: 70077:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1595.622298] LNet: Removed LNI 192.168.204.45@tcp [ 1595.887659] Key type .llcrypt unregistered [ 1595.888772] Key type ._llcrypt unregistered [ 1601.238585] Key type ._llcrypt registered [ 1601.239714] Key type .llcrypt registered [ 1601.450046] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1601.465647] alg: No test for adler32 (adler32-zlib) [ 1602.325539] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 1602.416049] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 1602.417535] LNet: Accept secure, port 988 [ 1603.999166] Key type lgssc registered [ 1604.345642] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1620.702296] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1621.804073] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1627.434430] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1642.994828] Lustre: Mounted lustre-client [ 1644.593684] LustreError: 72598:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda4606a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1644.598936] LustreError: 72598:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 1644.616160] Lustre: Unmounted lustre-client [ 1661.887874] Key type lgssc unregistered [ 1662.005621] LNet: 73001:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1663.077735] LNet: Removed LNI 192.168.204.45@tcp [ 1663.339414] Key type .llcrypt unregistered [ 1663.340939] Key type ._llcrypt unregistered [ 1668.257585] Key type ._llcrypt registered [ 1668.258587] Key type .llcrypt registered [ 1668.461463] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1668.466834] alg: No test for adler32 (adler32-zlib) [ 1669.327970] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 1669.418978] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 1669.420465] LNet: Accept secure, port 988 [ 1671.007167] Key type lgssc registered [ 1671.425516] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1727.439402] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1728.615814] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1734.340885] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1750.000866] Lustre: Mounted lustre-client [ 1750.426855] LustreError: 75928:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda4832f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1750.433673] LustreError: 75928:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 1750.452159] Lustre: Unmounted lustre-client [ 1776.067253] Key type lgssc unregistered [ 1776.179559] LNet: 76331:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1777.189816] LNet: Removed LNI 192.168.204.45@tcp [ 1777.391647] Key type .llcrypt unregistered [ 1777.392585] Key type ._llcrypt unregistered [ 1783.010625] Key type ._llcrypt registered [ 1783.011669] Key type .llcrypt registered [ 1783.450562] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 19:25:46 (1755041146) [ 1784.113150] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1784.143027] alg: No test for adler32 (adler32-zlib) [ 1784.998690] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 1785.071507] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 1785.073197] LNet: Accept secure, port 988 [ 1786.655137] Key type lgssc registered [ 1786.980749] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1802.862538] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1804.030357] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1809.940155] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1811.112178] Lustre: Mounted lustre-client [ 1813.136424] LustreError: 79437:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda45cb8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1813.144384] LustreError: 79437:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 1813.168217] Lustre: Unmounted lustre-client [ 1813.288723] Lustre: Mounted lustre-client [ 1813.869402] LustreError: 79514:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda458e0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1813.874509] LustreError: 79514:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 1813.875958] LustreError: 79514:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 1813.896200] Lustre: Unmounted lustre-client [ 1823.060862] Key type lgssc unregistered [ 1823.185803] LNet: 79914:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1824.229970] LNet: Removed LNI 192.168.204.45@tcp [ 1824.483613] Key type .llcrypt unregistered [ 1824.484592] Key type ._llcrypt unregistered [ 1829.634277] Key type ._llcrypt registered [ 1829.635346] Key type .llcrypt registered [ 1829.824400] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1829.829931] alg: No test for adler32 (adler32-zlib) [ 1830.691179] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 1830.787404] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 1830.788957] LNet: Accept secure, port 988 [ 1832.375160] Key type lgssc registered [ 1832.786564] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1848.542853] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1849.671673] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1855.048393] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1856.210241] Lustre: Mounted lustre-client [ 1857.872775] LustreError: 82444:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda46aaf000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1857.878447] LustreError: 82444:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 1857.898082] Lustre: Unmounted lustre-client [ 1875.055175] Key type lgssc unregistered [ 1875.195580] LNet: 82847:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1876.262349] LNet: Removed LNI 192.168.204.45@tcp [ 1876.617104] Key type .llcrypt unregistered [ 1876.619154] Key type ._llcrypt unregistered [ 1882.297372] Key type ._llcrypt registered [ 1882.299712] Key type .llcrypt registered [ 1882.903292] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 19:27:25 (1755041245) [ 1883.858936] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1883.866221] alg: No test for adler32 (adler32-zlib) [ 1884.729402] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 1884.818190] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 1884.821381] LNet: Accept secure, port 988 [ 1886.415232] Key type lgssc registered [ 1887.076691] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1903.717128] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1904.812386] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1910.410635] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1911.558735] Lustre: Mounted lustre-client [ 1920.506164] Lustre: lustre: nosquash_nids is cleared [ 1920.518512] Lustre: lustre: root_squash is set to 500:500 [ 1928.176377] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.204.45@tcp 192.168.0.[2,10]@tcp [ 1933.423986] Lustre: lustre: nosquash_nids set to 192.168.204.45/24@tcp [ 1939.284293] LustreError: 86335:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda44158000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1939.291778] LustreError: 86335:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 1939.312671] Lustre: Unmounted lustre-client [ 1957.702952] Key type lgssc unregistered [ 1957.824744] LNet: 86737:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1958.885764] LNet: Removed LNI 192.168.204.45@tcp [ 1959.296561] Key type .llcrypt unregistered [ 1959.297895] Key type ._llcrypt unregistered [ 1965.613650] Key type ._llcrypt registered [ 1965.614961] Key type .llcrypt registered [ 1966.265145] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 19:28:48 (1755041328) [ 1967.285405] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 1968.080204] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 19:28:50 (1755041330) [ 1968.928859] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1968.936466] alg: No test for adler32 (adler32-zlib) [ 1969.789890] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 1969.866853] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 1969.868346] LNet: Accept secure, port 988 [ 1971.439209] Key type lgssc registered [ 1971.900073] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1989.118431] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1990.685818] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1997.366767] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1997.536354] Lustre: lustre: root_squash is set to 500:500 [ 1997.543656] Lustre: lustre: nosquash_nids set to 192.168.204.45/24@tcp [ 2008.045712] Lustre: Mounted lustre-client [ 2010.214712] LustreError: 90193:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda467d3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2010.220431] LustreError: 90193:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2010.239149] Lustre: Unmounted lustre-client [ 2022.862837] Key type lgssc unregistered [ 2022.969521] LNet: 90595:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2024.037462] LNet: Removed LNI 192.168.204.45@tcp [ 2024.250649] Key type .llcrypt unregistered [ 2024.251637] Key type ._llcrypt unregistered [ 2031.482737] Key type ._llcrypt registered [ 2031.484431] Key type .llcrypt registered [ 2032.069380] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 2032.936886] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 19:29:55 (1755041395) [ 2040.382775] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2040.416433] alg: No test for adler32 (adler32-zlib) [ 2041.263294] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 2041.335297] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 2041.339142] LNet: Accept secure, port 988 [ 2042.943205] Key type lgssc registered [ 2043.512860] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2074.520223] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2076.277320] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2086.157213] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2113.346504] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2114.583158] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2121.298929] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2132.479266] Lustre: Mounted lustre-client [ 2145.606903] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9bda458e6800.ost_server_uuid 50 [ 2146.051112] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9bda458e6800.ost_server_uuid in FULL state after 0 sec [ 2146.176552] Lustre: Mounted lustre-client [ 2146.811204] LustreError: 97473:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda512ea000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2146.816853] LustreError: 97473:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 2146.839068] Lustre: Unmounted lustre-client [ 2176.152229] Key type lgssc unregistered [ 2176.307563] LNet: 98043:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2177.318396] LNet: Removed LNI 192.168.204.45@tcp [ 2177.711287] Key type .llcrypt unregistered [ 2177.713414] Key type ._llcrypt unregistered [ 2195.663737] Key type ._llcrypt registered [ 2195.665758] Key type .llcrypt registered [ 2195.819610] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2195.826356] alg: No test for adler32 (adler32-zlib) [ 2196.667518] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 2196.741566] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 2196.743253] LNet: Accept secure, port 988 [ 2198.319124] Key type lgssc registered [ 2198.648395] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2229.388635] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2230.494050] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2237.514311] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2260.013921] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 19:33:42 (1755041622) [ 2318.578559] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2327.118389] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2364.074915] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2367.513734] Lustre: Mounted lustre-client [ 2387.871298] Lustre: 98822:0:(client.c:2453:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1755041735/real 1755041735] req@000000002e1f5b58 x1840294458823936/t0(0) o400->lustre-OST0000-osc-ffff9bda6007b800@192.168.204.145@tcp:28/4 lens 224/224 e 0 to 1 dl 1755041751 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2387.891386] Lustre: lustre-OST0000-osc-ffff9bda6007b800: Connection to lustre-OST0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2393.631338] Lustre: 98822:0:(client.c:2453:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1755041741/real 1755041741] req@0000000069e80d5d x1840294458824448/t0(0) o400->lustre-OST0000-osc-ffff9bda6007b800@192.168.204.145@tcp:28/4 lens 224/224 e 0 to 1 dl 1755041757 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2398.256284] Lustre: lustre-OST0000-osc-ffff9bda6007b800: Connection restored to (at 192.168.204.145@tcp) [ 2399.135585] Lustre: 98825:0:(client.c:2453:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1755041746/real 1755041746] req@000000002613fb69 x1840294458824960/t0(0) o400->lustre-OST0000-osc-ffff9bda6007b800@192.168.204.145@tcp:28/4 lens 224/224 e 0 to 1 dl 1755041762 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2403.314315] Lustre: lustre-MDT0000-mdc-ffff9bda6007b800: Connection to lustre-MDT0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2415.584114] Lustre: 105951:0:(client.c:2453:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1755041762/real 1755041762] req@00000000b18d38ec x1840294458826752/t0(0) o101->MGC192.168.204.145@tcp@192.168.204.145@tcp:26/25 lens 328/344 e 0 to 1 dl 1755041778 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2415.619385] LustreError: MGC192.168.204.145@tcp: Connection to MGS (at 192.168.204.145@tcp) was lost; in progress operations using this service will fail [ 2415.639068] Lustre: 105951:0:(mgc_request.c:1876:mgc_process_log()) MGC192.168.204.145@tcp: IR log lustre-cliir failed, not fatal: rc = -5 [ 2422.722701] Lustre: lustre-MDT0000-mdc-ffff9bda6007b800: Connection restored to (at 192.168.204.145@tcp) [ 2425.131155] LustreError: 106676:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda6007b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2425.145268] LustreError: 106676:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2425.207281] Lustre: Unmounted lustre-client [ 2426.227766] Lustre: Evicted from MGS (at 192.168.204.145@tcp) after server handle changed from 0xa083971eda33247f to 0xa083971eda3327f8 [ 2426.239063] Lustre: MGC192.168.204.145@tcp: Connection restored to (at 192.168.204.145@tcp) [ 2426.257262] LustreError: 106676:0:(obd_class.h:479:obd_check_dev()) Device 0 not setup [ 2426.259857] LustreError: 106676:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [ 2461.642988] Key type lgssc unregistered [ 2461.975384] LNet: 107081:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2463.017878] LNet: Removed LNI 192.168.204.45@tcp [ 2463.701307] Key type .llcrypt unregistered [ 2463.704848] Key type ._llcrypt unregistered [ 2479.884243] Key type ._llcrypt registered [ 2479.890081] Key type .llcrypt registered [ 2481.427587] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 19:37:23 (1755041843) [ 2495.820051] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2495.903453] alg: No test for adler32 (adler32-zlib) [ 2496.949400] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 2497.126608] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 2497.130715] LNet: Accept secure, port 988 [ 2498.783218] Key type lgssc registered [ 2499.751413] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2529.649625] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2615.221467] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2619.323841] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2645.434329] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2648.319320] Lustre: Mounted lustre-client [ 2651.532518] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 2655.111555] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 19:40:15 (1755042015) [ 2712.038588] Lustre: 113658:0:(client.c:2453:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1755042059/real 1755042059] req@00000000b5897853 x1840294773612544/t0(0) o101->lustre-MDT0000-mdc-ffff9bda60bee000@192.168.204.145@tcp:12/10 lens 584/3744 e 0 to 1 dl 1755042075 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:0 [ 2712.072081] Lustre: lustre-MDT0000-mdc-ffff9bda60bee000: Connection to lustre-MDT0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2712.106680] Lustre: lustre-MDT0000-mdc-ffff9bda60bee000: Connection restored to (at 192.168.204.145@tcp) [ 2735.583774] Lustre: 113792:0:(client.c:2453:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1755042082/real 1755042082] req@000000007ada846a x1840294773697792/t0(0) o101->lustre-MDT0000-mdc-ffff9bda60bee000@192.168.204.145@tcp:12/10 lens 584/4776 e 0 to 1 dl 1755042098 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:0 [ 2735.642767] Lustre: lustre-MDT0000-mdc-ffff9bda60bee000: Connection to lustre-MDT0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2735.694041] Lustre: lustre-MDT0000-mdc-ffff9bda60bee000: Connection restored to (at 192.168.204.145@tcp) [ 2778.591116] Lustre: 114250:0:(client.c:2453:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1755042125/real 1755042125] req@00000000b98100f2 x1840294773988608/t0(0) o101->lustre-MDT0000-mdc-ffff9bda60bee000@192.168.204.145@tcp:12/10 lens 584/8360 e 0 to 1 dl 1755042141 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:0 [ 2778.630812] Lustre: lustre-MDT0000-mdc-ffff9bda60bee000: Connection to lustre-MDT0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2778.693358] Lustre: lustre-MDT0000-mdc-ffff9bda60bee000: Connection restored to (at 192.168.204.145@tcp) [ 2802.144289] Lustre: 114421:0:(client.c:2453:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1755042149/real 1755042149] req@000000002169728e x1840294774098048/t0(0) o101->lustre-MDT0000-mdc-ffff9bda60bee000@192.168.204.145@tcp:12/10 lens 584/9696 e 0 to 1 dl 1755042165 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:0 [ 2802.188337] Lustre: lustre-MDT0000-mdc-ffff9bda60bee000: Connection to lustre-MDT0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2802.230909] Lustre: lustre-MDT0000-mdc-ffff9bda60bee000: Connection restored to (at 192.168.204.145@tcp) [ 2848.223228] Lustre: 115061:0:(client.c:2453:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1755042195/real 1755042195] req@00000000dd47dc6a x1840294774505984/t0(0) o101->lustre-MDT0000-mdc-ffff9bda60bee000@192.168.204.145@tcp:12/10 lens 584/14744 e 0 to 1 dl 1755042211 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:0 [ 2848.252860] Lustre: lustre-MDT0000-mdc-ffff9bda60bee000: Connection to lustre-MDT0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2848.278117] Lustre: lustre-MDT0000-mdc-ffff9bda60bee000: Connection restored to (at 192.168.204.145@tcp) [ 2916.831169] Lustre: 116724:0:(client.c:2453:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1755042264/real 1755042264] req@00000000d67e2e5c x1840294775568000/t0(0) o101->lustre-MDT0000-mdc-ffff9bda60bee000@192.168.204.145@tcp:12/10 lens 584/27944 e 0 to 1 dl 1755042280 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:0 [ 2916.842269] Lustre: lustre-MDT0000-mdc-ffff9bda60bee000: Connection to lustre-MDT0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2916.859472] Lustre: lustre-MDT0000-mdc-ffff9bda60bee000: Connection restored to (at 192.168.204.145@tcp) [ 2960.351404] Lustre: 117664:0:(client.c:2453:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1755042307/real 1755042307] req@00000000de38a50f x1840294776169216/t0(0) o101->lustre-MDT0000-mdc-ffff9bda60bee000@192.168.204.145@tcp:12/10 lens 584/35408 e 0 to 1 dl 1755042323 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:0 [ 2960.363400] Lustre: lustre-MDT0000-mdc-ffff9bda60bee000: Connection to lustre-MDT0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2960.380570] Lustre: lustre-MDT0000-mdc-ffff9bda60bee000: Connection restored to (at 192.168.204.145@tcp) [ 2967.891778] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2978.155516] LustreError: 117876:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2978.183528] Lustre: Unmounted lustre-client [ 3049.347313] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3050.789143] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3066.250102] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3108.198830] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 19:47:50 (1755042470) [ 3156.183590] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3157.675073] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3166.780400] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3166.972421] Lustre: Mounted lustre-client [ 3168.350815] LustreError: 125276:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda458e0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3168.363916] LustreError: 125276:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3168.366754] LustreError: 125276:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 3168.393746] Lustre: Unmounted lustre-client [ 3189.391461] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 19:49:12 (1755042552) [ 3234.745253] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3236.876758] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3247.497533] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3247.738939] Lustre: Mounted lustre-client [ 3249.474918] LustreError: 129314:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda503d8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3249.486788] LustreError: 129314:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3249.489646] LustreError: 129314:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 3249.525325] Lustre: Unmounted lustre-client [ 3268.872515] Key type lgssc unregistered [ 3269.027182] LNet: 129717:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3270.054357] LNet: Removed LNI 192.168.204.45@tcp [ 3270.381654] Key type .llcrypt unregistered [ 3270.383299] Key type ._llcrypt unregistered [ 3278.546158] Key type ._llcrypt registered [ 3278.548219] Key type .llcrypt registered [ 3279.281247] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 19:50:41 (1755042641) [ 3280.521875] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3280.528892] alg: No test for adler32 (adler32-zlib) [ 3281.443340] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 3281.564266] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 3281.566849] LNet: Accept secure, port 988 [ 3283.159193] Key type lgssc registered [ 3283.711757] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3315.264736] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3317.835417] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3329.995562] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3332.507736] Lustre: Mounted lustre-client [ 3347.862334] LustreError: 132810:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda47c47000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3347.886679] LustreError: 132810:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3347.950706] Lustre: Unmounted lustre-client [ 3371.316725] Key type lgssc unregistered [ 3371.450055] LNet: 133212:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3372.519540] LNet: Removed LNI 192.168.204.45@tcp [ 3372.944700] Key type .llcrypt unregistered [ 3372.946494] Key type ._llcrypt unregistered [ 3381.464661] Key type ._llcrypt registered [ 3381.466196] Key type .llcrypt registered [ 3382.244688] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 19:52:24 (1755042744) [ 3383.292888] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3383.362695] alg: No test for adler32 (adler32-zlib) [ 3384.294990] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 3384.440561] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 3384.444676] LNet: Accept secure, port 988 [ 3386.071175] Key type lgssc registered [ 3386.676571] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3412.170141] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3413.946609] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3422.534803] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3427.846985] Lustre: Mounted lustre-client [ 3432.931911] Lustre: lustre-OST0000-osc-ffff9bda45cb8800: Connection to lustre-OST0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3448.596559] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff9bda45cb8800.ost_server_uuid 50 [ 3449.680112] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 3459.880207] LustreError: 136740:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda45cb8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3459.887162] LustreError: 136740:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3459.928592] Lustre: Unmounted lustre-client [ 3476.147438] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 19:53:58 (1755042838) [ 3495.611503] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3497.938873] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3510.477263] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3522.946657] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3523.279071] Lustre: Mounted lustre-client [ 3528.689012] Lustre: lustre-OST0000-osc-ffff9bda60bee000: Connection to lustre-OST0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3546.400355] LustreError: 139392:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda60bee000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3546.415893] LustreError: 139392:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 3546.419084] LustreError: 139392:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 3546.451505] Lustre: Unmounted lustre-client [ 3573.032992] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 19:55:35 (1755042935) [ 3583.865939] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3585.283555] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3592.749848] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3600.925836] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3601.255100] Lustre: Mounted lustre-client [ 3606.500073] Lustre: lustre-OST0000-osc-ffff9bda458e6000: Connection to lustre-OST0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3618.921585] LustreError: 142142:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda458e6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3618.926917] LustreError: 142142:0:(lov_obd.c:784:lov_cleanup()) Skipped 1 previous similar message [ 3618.938610] LustreError: 142142:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 3618.941368] LustreError: 142142:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 3618.974339] Lustre: Unmounted lustre-client [ 3649.184436] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 19:56:51 (1755043011) [ 3696.098927] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3697.942733] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3706.671381] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3736.979637] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3738.210555] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3744.412666] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3767.773994] Lustre: Mounted lustre-client [ 3794.990757] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3795.567841] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 3795.877574] LustreError: 148721:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda47c42000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3795.880538] LustreError: 148721:0:(lov_obd.c:784:lov_cleanup()) Skipped 1 previous similar message [ 3795.885460] LustreError: 148721:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3795.888204] LustreError: 148721:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 3795.908112] Lustre: Unmounted lustre-client [ 3814.266726] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 19:59:37 (1755043177) [ 3824.640410] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3825.900131] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3832.375298] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3851.603267] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3867.164090] Lustre: Mounted lustre-client [ 3894.796391] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3895.361454] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 3897.827403] Lustre: lustre-OST0001-osc-ffff9bda4606d800: Connection to lustre-OST0001 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3903.141382] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3913.383841] LustreError: 152304:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 3913.387765] LustreError: 152304:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 3913.405198] Lustre: Unmounted lustre-client [ 3951.845135] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 20:01:54 (1755043314) [ 3961.674333] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3962.922745] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3968.967027] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3969.139668] Lustre: Mounted lustre-client [ 3976.531261] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3977.077347] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 3981.017864] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9bda458e2800.ost_server_uuid 50 [ 3981.510392] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9bda458e2800.ost_server_uuid in FULL state after 0 sec [ 3982.155084] LustreError: 155719:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda458e2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3982.160755] LustreError: 155719:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 3982.162277] LustreError: 155719:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 3982.182299] Lustre: Unmounted lustre-client [ 3982.282992] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 3982.285101] Lustre: Skipped 3 previous similar messages [ 3982.687731] LustreError: 155783:0:(sec.c:404:import_sec_validate_get()) import 00000000875de357 (NEW) with no sec [ 4013.665052] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 20:02:56 (1755043376) [ 4024.724433] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4025.934872] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4032.269918] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4038.739730] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4038.894913] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 4038.946174] Lustre: Mounted lustre-client [ 4038.947101] Lustre: Skipped 1 previous similar message [ 4048.871098] LustreError: 158637:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda48a3f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4048.876273] LustreError: 158637:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 4048.887292] LustreError: 158637:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 4048.890375] LustreError: 158637:0:(obd_class.h:479:obd_check_dev()) Skipped 18 previous similar messages [ 4048.921085] Lustre: Unmounted lustre-client [ 4048.922222] Lustre: Skipped 1 previous similar message [ 4073.814941] Key type lgssc unregistered [ 4073.937655] LNet: 159085:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4074.982542] LNet: Removed LNI 192.168.204.45@tcp [ 4075.257618] Key type .llcrypt unregistered [ 4075.258637] Key type ._llcrypt unregistered [ 4081.419418] Key type ._llcrypt registered [ 4081.420556] Key type .llcrypt registered [ 4081.870961] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 20:04:04 (1755043444) [ 4082.166035] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4082.172745] alg: No test for adler32 (adler32-zlib) [ 4083.048355] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 4083.137506] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 4083.139339] LNet: Accept secure, port 988 [ 4084.727202] Key type lgssc registered [ 4085.197638] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4103.957218] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4105.123826] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4111.402916] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4117.903533] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4118.044774] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 4123.134198] Lustre: Mounted lustre-client [ 4131.821867] LustreError: lustre-MDT0001-mdc-ffff9bda47c40800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 4131.831061] Lustre: lustre-MDT0001-mdc-ffff9bda47c40800: Connection restored to 192.168.204.145@tcp (at 192.168.204.145@tcp) [ 4133.061880] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4133.639399] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 4141.866988] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 4148.611121] LustreError: 163307:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda47c40800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4148.615296] LustreError: 163307:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 4148.638141] Lustre: Unmounted lustre-client [ 4169.408237] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 20:05:32 (1755043532) [ 4205.532652] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4207.020674] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4215.457860] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4215.675471] Lustre: Mounted lustre-client [ 4227.596491] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 4228.278003] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 4240.359650] Lustre: lustre-OST0001-osc-ffff9bda6007f000: Connection to lustre-OST0001 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4244.595141] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4254.887845] LustreError: 168030:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 4254.889739] LustreError: 168030:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 4254.905085] Lustre: Unmounted lustre-client [ 4287.912819] Key type lgssc unregistered [ 4288.050884] LNet: 168436:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4289.126507] LNet: Removed LNI 192.168.204.45@tcp [ 4289.538994] Key type .llcrypt unregistered [ 4289.540629] Key type ._llcrypt unregistered [ 4307.398546] Key type ._llcrypt registered [ 4307.401655] Key type .llcrypt registered [ 4307.569031] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4307.573863] alg: No test for adler32 (adler32-zlib) [ 4308.444300] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 4308.534762] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 4308.536474] LNet: Accept secure, port 988 [ 4310.119250] Key type lgssc registered [ 4310.739734] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4343.324977] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4344.881267] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4353.778310] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4376.916403] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 20:08:59 (1755043739) [ 4388.411638] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4390.001998] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4396.761790] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4407.286543] Lustre: Mounted lustre-client [ 4444.480837] LustreError: 174505:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda4769c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4444.489139] LustreError: 174505:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4444.508088] Lustre: Unmounted lustre-client [ 4462.175497] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4462.318123] Lustre: Mounted lustre-client [ 4494.634905] LustreError: 175162:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda45722000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4494.642972] LustreError: 175162:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4494.644703] LustreError: 175162:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4494.666264] Lustre: Unmounted lustre-client [ 4513.585432] Key type lgssc unregistered [ 4513.737830] LNet: 175570:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4514.790548] LNet: Removed LNI 192.168.204.45@tcp [ 4515.183152] Key type .llcrypt unregistered [ 4515.184973] Key type ._llcrypt unregistered [ 4522.804352] Key type ._llcrypt registered [ 4522.805276] Key type .llcrypt registered [ 4523.367928] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 20:11:26 (1755043886) [ 4524.148230] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4524.229070] alg: No test for adler32 (adler32-zlib) [ 4525.131656] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 4525.250053] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 4525.252586] LNet: Accept secure, port 988 [ 4526.855222] Key type lgssc registered [ 4527.271079] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4546.608640] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4548.353493] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4555.920484] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4566.523093] Lustre: Mounted lustre-client [ 4573.661801] LustreError: 178820:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda4606f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4573.667387] LustreError: 178820:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4573.685084] Lustre: Unmounted lustre-client [ 4590.947639] Key type lgssc unregistered [ 4591.061345] LNet: 179222:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4592.103223] LNet: Removed LNI 192.168.204.45@tcp [ 4592.455970] Key type .llcrypt unregistered [ 4592.457754] Key type ._llcrypt unregistered [ 4598.460306] Key type ._llcrypt registered [ 4598.461264] Key type .llcrypt registered [ 4598.623341] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4598.628095] alg: No test for adler32 (adler32-zlib) [ 4599.475432] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 4599.551856] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 4599.553347] LNet: Accept secure, port 988 [ 4601.127241] Key type lgssc registered [ 4601.659243] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4624.170428] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4625.701264] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4631.702243] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4642.301186] Lustre: Mounted lustre-client [ 4645.483242] LustreError: 181785:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda467d4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4645.497142] LustreError: 181785:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4645.531342] Lustre: Unmounted lustre-client [ 4663.725256] Key type lgssc unregistered [ 4663.856597] LNet: 182187:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4664.870157] LNet: Removed LNI 192.168.204.45@tcp [ 4665.293886] Key type .llcrypt unregistered [ 4665.296056] Key type ._llcrypt unregistered [ 4672.223118] Key type ._llcrypt registered [ 4672.224257] Key type .llcrypt registered [ 4672.814973] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 20:13:55 (1755044035) [ 4673.772198] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4673.788172] alg: No test for adler32 (adler32-zlib) [ 4674.670175] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 4674.761655] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 4674.763161] LNet: Accept secure, port 988 [ 4676.351198] Key type lgssc registered [ 4676.770197] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4695.368552] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4696.943787] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4704.579649] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4714.997647] Lustre: Mounted lustre-client [ 4723.367083] LustreError: 185436:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda44a8f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4723.373341] LustreError: 185436:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4723.397449] Lustre: Unmounted lustre-client [ 4741.756397] Key type lgssc unregistered [ 4741.908925] LNet: 185839:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4742.950483] LNet: Removed LNI 192.168.204.45@tcp [ 4743.329352] Key type .llcrypt unregistered [ 4743.330597] Key type ._llcrypt unregistered [ 4748.886649] Key type ._llcrypt registered [ 4748.887978] Key type .llcrypt registered [ 4749.053869] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4749.058917] alg: No test for adler32 (adler32-zlib) [ 4749.945940] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 4750.038900] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 4750.040362] LNet: Accept secure, port 988 [ 4751.631202] Key type lgssc registered [ 4751.991107] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4770.550986] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4771.967381] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4779.028552] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4789.761039] Lustre: Mounted lustre-client [ 4792.810946] LustreError: 188398:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda4440c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4792.823186] LustreError: 188398:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4792.858205] Lustre: Unmounted lustre-client [ 4803.793184] Key type lgssc unregistered [ 4803.953225] LNet: 188799:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4804.966930] LNet: Removed LNI 192.168.204.45@tcp [ 4805.298435] Key type .llcrypt unregistered [ 4805.299756] Key type ._llcrypt unregistered [ 4812.154890] Key type ._llcrypt registered [ 4812.155837] Key type .llcrypt registered [ 4812.615246] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 20:16:15 (1755044175) [ 4821.478529] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4821.489693] alg: No test for adler32 (adler32-zlib) [ 4822.362802] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 4822.448249] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 4822.449707] LNet: Accept secure, port 988 [ 4824.023230] Key type lgssc registered [ 4824.615402] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4859.594591] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4861.106762] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4869.786617] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4895.087555] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 20:17:37 (1755044257) [ 4907.494118] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4909.167731] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4916.682982] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4927.483248] Lustre: Mounted lustre-client [ 4929.681589] LustreError: 195296:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda44a8b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4929.692866] LustreError: 195296:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4929.722179] Lustre: Unmounted lustre-client [ 4949.130468] Key type lgssc unregistered [ 4949.309737] LNet: 195699:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4950.375334] LNet: Removed LNI 192.168.204.45@tcp [ 4950.747944] Key type .llcrypt unregistered [ 4950.749738] Key type ._llcrypt unregistered [ 4957.247373] Key type ._llcrypt registered [ 4957.248430] Key type .llcrypt registered [ 4957.885119] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 4958.708551] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 20:18:41 (1755044321) [ 4966.186055] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4966.219101] alg: No test for adler32 (adler32-zlib) [ 4967.108241] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 4967.195168] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 4967.196891] LNet: Accept secure, port 988 [ 4968.799347] Key type lgssc registered [ 4969.451975] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5009.303051] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5010.843898] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5019.336940] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid [ 5026.654277] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid [ 5027.821638] Lustre: Mounted lustre-client [ 5034.385120] LustreError: 201224:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda4440d000: lov tgt 1000 not cleaned! deathrow=0, lovrc=1 [ 5034.396280] LustreError: 201224:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5034.427230] Lustre: Unmounted lustre-client [ 5104.613724] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5106.123625] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5114.062168] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5145.666081] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 20:21:48 (1755044508) [ 5146.199487] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 5147.019668] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 20:21:49 (1755044509) [ 5167.987025] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5169.531380] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5185.069798] Key type lgssc unregistered [ 5185.217087] LNet: 207388:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5186.277975] LNet: Removed LNI 192.168.204.45@tcp [ 5186.564092] Key type .llcrypt unregistered [ 5186.564992] Key type ._llcrypt unregistered [ 5192.701542] Key type ._llcrypt registered [ 5192.702866] Key type .llcrypt registered [ 5193.128950] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 20:22:36 (1755044556) [ 5205.059416] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5205.076198] alg: No test for adler32 (adler32-zlib) [ 5205.916772] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 5205.996208] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 5205.998371] LNet: Accept secure, port 988 [ 5207.607209] Key type lgssc registered [ 5208.238563] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5222.865252] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5224.045393] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5229.772950] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5247.006019] Key type lgssc unregistered [ 5247.130524] LNet: 211007:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5247.140417] LNet: Removed LNI 192.168.204.45@tcp [ 5247.409350] Key type .llcrypt unregistered [ 5247.410392] Key type ._llcrypt unregistered [ 5253.591096] Key type ._llcrypt registered [ 5253.592225] Key type .llcrypt registered [ 5254.082681] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 20:23:36 (1755044616) [ 5255.102541] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5255.111187] alg: No test for adler32 (adler32-zlib) [ 5255.991134] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 5256.090164] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 5256.093382] LNet: Accept secure, port 988 [ 5257.687167] Key type lgssc registered [ 5258.061682] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5275.426447] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5276.707883] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5283.756852] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5294.581178] Lustre: Mounted lustre-client [ 5299.683797] Lustre: lustre-MDT0000-mdc-ffff9bda46069000: Connection to lustre-MDT0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5304.802325] Lustre: lustre-MDT0001-mdc-ffff9bda46069000: Connection to lustre-MDT0001 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5320.161898] LustreError: MGC192.168.204.145@tcp: Connection to MGS (at 192.168.204.145@tcp) was lost; in progress operations using this service will fail [ 5320.168812] Lustre: Evicted from MGS (at 192.168.204.145@tcp) after server handle changed from 0xbee30016f0b2e68c to 0xbee30016f0b2ffec [ 5320.172605] Lustre: MGC192.168.204.145@tcp: Connection restored to 192.168.204.145@tcp (at 192.168.204.145@tcp) [ 5337.651925] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5340.642039] LustreError: lustre-MDT0000-mdc-ffff9bda46069000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 5340.657318] Lustre: lustre-MDT0000-mdc-ffff9bda46069000: Connection restored to 192.168.204.145@tcp (at 192.168.204.145@tcp) [ 5341.173917] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 3 sec [ 5342.225492] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5342.701754] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 5343.295969] LustreError: 215429:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda46069000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5343.299872] LustreError: 215429:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5343.316919] Lustre: Unmounted lustre-client [ 5359.745367] Key type lgssc unregistered [ 5359.863539] LNet: 215830:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5360.933748] LNet: Removed LNI 192.168.204.45@tcp [ 5361.166111] Key type .llcrypt unregistered [ 5361.167074] Key type ._llcrypt unregistered [ 5366.784537] Key type ._llcrypt registered [ 5366.785481] Key type .llcrypt registered [ 5367.188230] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 20:25:30 (1755044730) [ 5367.912784] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5367.932455] alg: No test for adler32 (adler32-zlib) [ 5368.789026] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 5368.868926] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 5368.870317] LNet: Accept secure, port 988 [ 5370.447125] Key type lgssc registered [ 5370.753200] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5386.624433] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5387.749380] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5411.462574] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5413.251321] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5422.928219] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5428.684265] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5453.379692] Key type lgssc unregistered [ 5453.501349] LNet: 220933:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5453.508427] LNet: Removed LNI 192.168.204.45@tcp [ 5453.771459] Key type .llcrypt unregistered [ 5453.772473] Key type ._llcrypt unregistered [ 5468.068496] Key type ._llcrypt registered [ 5468.069550] Key type .llcrypt registered [ 5468.252792] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5468.271012] alg: No test for adler32 (adler32-zlib) [ 5469.117493] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 5469.194665] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 5469.196336] LNet: Accept secure, port 988 [ 5470.775131] Key type lgssc registered [ 5471.083066] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5503.387949] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5504.621455] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5511.597995] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5528.063664] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 20:28:11 (1755044891) [ 5563.482544] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5564.647929] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5573.784276] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5604.633783] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 20:29:27 (1755044967) [ 5607.583705] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 20:29:30 (1755044970) [ 5618.630293] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5620.072675] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5627.430485] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5638.142372] Lustre: Mounted lustre-client [ 5641.783980] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [ 5642.852918] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 5643.630077] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 5644.291661] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [ 5648.353945] Lustre: lustre-MDT0000-mdc-ffff9bda503d9800: Connection to lustre-MDT0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5663.711237] Lustre: 221712:0:(client.c:2453:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1755045011/real 1755045011] req@000000008467d9d5 x1840297889769216/t0(0) o400->MGC192.168.204.145@tcp@192.168.204.145@tcp:26/25 lens 224/224 e 0 to 1 dl 1755045027 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 5663.715434] LustreError: MGC192.168.204.145@tcp: Connection to MGS (at 192.168.204.145@tcp) was lost; in progress operations using this service will fail [ 5663.733542] Lustre: Evicted from MGS (at 192.168.204.145@tcp) after server handle changed from 0x8fe6b14f73fa550 to 0x8fe6b14f73fa7b8 [ 5663.739231] Lustre: MGC192.168.204.145@tcp: Connection restored to 192.168.204.145@tcp (at 192.168.204.145@tcp) [ 5664.683562] Lustre: lustre-MDT0000-mdc-ffff9bda503d9800: Connection restored to 192.168.204.145@tcp (at 192.168.204.145@tcp) [ 5666.077448] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5666.633778] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 5668.726805] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [ 5673.955756] Lustre: lustre-MDT0000-mdc-ffff9bda503d9800: Connection to lustre-MDT0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5684.195372] LustreError: MGC192.168.204.145@tcp: Connection to MGS (at 192.168.204.145@tcp) was lost; in progress operations using this service will fail [ 5684.205635] Lustre: 232083:0:(mgc_request.c:1876:mgc_process_log()) MGC192.168.204.145@tcp: IR log lustre-cliir failed, not fatal: rc = -5 [ 5684.210555] Lustre: Evicted from MGS (at 192.168.204.145@tcp) after server handle changed from 0x8fe6b14f73fa7b8 to 0x8fe6b14f73faa9e [ 5684.218667] Lustre: MGC192.168.204.145@tcp: Connection restored to 192.168.204.145@tcp (at 192.168.204.145@tcp) [ 5688.745255] Lustre: lustre-MDT0000-mdc-ffff9bda503d9800: Connection restored to 192.168.204.145@tcp (at 192.168.204.145@tcp) [ 5690.353372] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5691.099418] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 5692.724435] LustreError: 234524:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda503d9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5692.731379] LustreError: 234524:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5692.749107] Lustre: Unmounted lustre-client [ 5728.967157] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5730.660752] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5738.471939] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5749.235970] Lustre: Mounted lustre-client [ 5749.665638] LustreError: 236144:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda44408000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5749.671587] LustreError: 236144:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5749.673331] LustreError: 236144:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5749.694085] Lustre: Unmounted lustre-client [ 5776.636575] Key type lgssc unregistered [ 5776.774904] LNet: 236547:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5777.829853] LNet: Removed LNI 192.168.204.45@tcp [ 5778.139092] Key type .llcrypt unregistered [ 5778.140040] Key type ._llcrypt unregistered [ 5785.853131] Key type ._llcrypt registered [ 5785.854925] Key type .llcrypt registered [ 5786.528688] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 20:32:29 (1755045149) [ 5788.295572] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5788.321117] alg: No test for adler32 (adler32-zlib) [ 5789.184523] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 5789.263262] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 5789.265028] LNet: Accept secure, port 988 [ 5790.847206] Key type lgssc registered [ 5791.338119] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5809.089752] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5810.216543] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5817.850945] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5828.608933] Lustre: Mounted lustre-client [ 5831.617449] LustreError: 239705:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda465fd800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5831.622610] LustreError: 239705:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5831.641138] Lustre: Unmounted lustre-client [ 5866.740955] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5868.071653] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5874.891680] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5885.435014] Lustre: Mounted lustre-client [ 5891.891756] LustreError: 241303:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda445d1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5891.899030] LustreError: 241303:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5891.903211] LustreError: 241303:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5891.941829] Lustre: Unmounted lustre-client [ 5928.488309] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5929.708977] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5936.241292] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5946.877627] Lustre: Mounted lustre-client [ 5947.236830] LustreError: 242917:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda78439800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5947.242285] LustreError: 242917:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5947.243797] LustreError: 242917:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5947.264913] Lustre: Unmounted lustre-client [ 5965.765059] Key type lgssc unregistered [ 5965.922797] LNet: 243320:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5966.950459] LNet: Removed LNI 192.168.204.45@tcp [ 5967.368328] Key type .llcrypt unregistered [ 5967.370637] Key type ._llcrypt unregistered [ 5974.553209] Key type ._llcrypt registered [ 5974.554765] Key type .llcrypt registered [ 5975.160397] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 20:35:37 (1755045337) [ 5976.424791] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5976.431422] alg: No test for adler32 (adler32-zlib) [ 5977.371018] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 5977.502470] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 5977.504273] LNet: Accept secure, port 988 [ 5979.111324] Key type lgssc registered [ 5979.612585] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5996.379704] Key type lgssc unregistered [ 5996.533674] LNet: 245626:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5996.542531] LNet: Removed LNI 192.168.204.45@tcp [ 5996.874651] Key type .llcrypt unregistered [ 5996.876549] Key type ._llcrypt unregistered [ 6007.453815] Key type ._llcrypt registered [ 6007.456137] Key type .llcrypt registered [ 6007.637431] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6007.645103] alg: No test for adler32 (adler32-zlib) [ 6008.507827] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 6008.596891] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6008.598505] LNet: Accept secure, port 988 [ 6010.191260] Key type lgssc registered [ 6010.671460] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6046.830950] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6048.620421] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6058.257950] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6078.007466] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [ 6078.668021] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 20:37:21 (1755045441) [ 6091.183662] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6092.449732] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6099.505084] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6108.181740] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 6109.438479] Lustre: Mounted lustre-client [ 6114.786298] Lustre: lustre-OST0001-osc-ffff9bda504f5000: Connection to lustre-OST0001 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6117.737638] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6127.977402] LustreError: 252108:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 6127.994095] Lustre: Unmounted lustre-client [ 6153.605213] Key type lgssc unregistered [ 6153.733591] LNet: 252513:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6154.791302] LNet: Removed LNI 192.168.204.45@tcp [ 6155.156326] Key type .llcrypt unregistered [ 6155.158524] Key type ._llcrypt unregistered [ 6174.756778] Key type ._llcrypt registered [ 6174.757750] Key type .llcrypt registered [ 6174.982625] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6174.989559] alg: No test for adler32 (adler32-zlib) [ 6175.880695] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 6175.977310] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6175.978881] LNet: Accept secure, port 988 [ 6177.575221] Key type lgssc registered [ 6178.192342] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6213.331130] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6214.943435] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6223.645814] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6242.032819] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 20:40:04 (1755045604) [ 6260.714775] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6261.923257] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6273.866944] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 20:40:36 (1755045636) [ 6284.867372] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6286.289950] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6293.198485] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6303.730780] Lustre: Mounted lustre-client [ 6306.549980] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 6314.257844] LustreError: 260336:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6314.281159] Lustre: Unmounted lustre-client [ 6368.336412] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6369.855040] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6401.904467] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6412.801449] Lustre: Mounted lustre-client [ 6413.300937] LustreError: 262391:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda465fd800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6413.308653] LustreError: 262391:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6413.310738] LustreError: 262391:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 6413.341162] Lustre: Unmounted lustre-client [ 6432.045351] Key type lgssc unregistered [ 6432.209668] LNet: 262794:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6433.253899] LNet: Removed LNI 192.168.204.45@tcp [ 6433.478269] Key type .llcrypt unregistered [ 6433.479404] Key type ._llcrypt unregistered [ 6445.815211] Key type ._llcrypt registered [ 6445.816534] Key type .llcrypt registered [ 6445.996716] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6446.000985] alg: No test for adler32 (adler32-zlib) [ 6446.902874] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 6447.004714] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6447.006938] LNet: Accept secure, port 988 [ 6448.599194] Key type lgssc registered [ 6449.246901] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6486.184460] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6488.025206] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6497.785966] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6522.625621] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [ 6523.440578] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 20:44:46 (1755045886) [ 6536.469330] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6537.921826] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6546.084209] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6552.572648] Lustre: Mounted lustre-client [ 6553.424362] LustreError: 268826:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda45cbe800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6553.433435] LustreError: 268826:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6553.462818] Lustre: Unmounted lustre-client [ 6572.039409] Key type lgssc unregistered [ 6572.204538] LNet: 269229:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6573.222439] LNet: Removed LNI 192.168.204.45@tcp [ 6573.641165] Key type .llcrypt unregistered [ 6573.643251] Key type ._llcrypt unregistered [ 6581.110968] Key type ._llcrypt registered [ 6581.112374] Key type .llcrypt registered [ 6581.530213] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [ 6582.104181] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 20:45:44 (1755045944) [ 6586.143316] Key type .llcrypt unregistered [ 6586.145471] Key type ._llcrypt unregistered [ 6588.888628] Key type ._llcrypt registered [ 6588.889592] Key type .llcrypt registered [ 6589.149676] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6589.160750] alg: No test for adler32 (adler32-zlib) [ 6590.036907] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 6590.127590] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6590.129377] LNet: Accept secure, port 988 [ 6591.719260] Key type lgssc registered [ 6592.120451] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6614.987287] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6626.290508] Lustre: Mounted lustre-client [ 6626.809843] LustreError: 272232:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda4832d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6626.819264] LustreError: 272232:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6626.842768] Lustre: Unmounted lustre-client [ 6645.795361] Key type lgssc unregistered [ 6645.940799] LNet: 272635:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6647.014392] LNet: Removed LNI 192.168.204.45@tcp [ 6647.371913] Key type .llcrypt unregistered [ 6647.372877] Key type ._llcrypt unregistered [ 6654.088407] Key type ._llcrypt registered [ 6654.089627] Key type .llcrypt registered [ 6654.612982] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 20:46:57 (1755046017) [ 6655.610483] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6655.670490] alg: No test for adler32 (adler32-zlib) [ 6656.593490] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 6656.726579] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6656.729653] LNet: Accept secure, port 988 [ 6658.351123] Key type lgssc registered [ 6658.895185] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6726.703858] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6738.911552] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6740.724975] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6741.969513] Lustre: Mounted lustre-client [ 6742.493658] LustreError: 275671:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda512b0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6742.502143] LustreError: 275671:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6742.533124] Lustre: Unmounted lustre-client [ 6761.386307] Key type lgssc unregistered [ 6761.529758] LNet: 276073:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6762.598612] LNet: Removed LNI 192.168.204.45@tcp [ 6762.898823] Key type .llcrypt unregistered [ 6762.899745] Key type ._llcrypt unregistered [ 6770.072451] Key type ._llcrypt registered [ 6770.074489] Key type .llcrypt registered [ 6770.651668] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 20:48:53 (1755046133) [ 6771.626719] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6771.633248] alg: No test for adler32 (adler32-zlib) [ 6772.536075] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 6772.638694] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6772.641686] LNet: Accept secure, port 988 [ 6774.271197] Key type lgssc registered [ 6774.738881] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6795.737691] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6796.888151] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6804.951807] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6815.739918] Lustre: Mounted lustre-client [ 6820.836456] Lustre: lustre-MDT0000-mdc-ffff9bda7843d800: Connection to lustre-MDT0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6824.046191] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6824.059726] LustreError: 279133:0:(file.c:6195:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [ 6824.207635] LustreError: 279152:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda7843d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6824.210084] LustreError: MGC192.168.204.145@tcp: Connection to MGS (at 192.168.204.145@tcp) was lost; in progress operations using this service will fail [ 6824.217209] LustreError: 279152:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6824.244800] Lustre: Unmounted lustre-client [ 6836.021675] Key type lgssc unregistered [ 6836.160653] LNet: 279545:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6837.221383] LNet: Removed LNI 192.168.204.45@tcp [ 6837.548796] Key type .llcrypt unregistered [ 6837.550435] Key type ._llcrypt unregistered [ 6844.381542] Key type ._llcrypt registered [ 6844.382547] Key type .llcrypt registered [ 6844.989041] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 20:50:07 (1755046207) [ 6845.954965] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6845.964760] alg: No test for adler32 (adler32-zlib) [ 6846.802840] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 6846.872758] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6846.874129] LNet: Accept secure, port 988 [ 6848.447188] Key type lgssc registered [ 6848.992765] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6869.957827] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6871.573145] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6879.471236] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6889.966436] Lustre: Mounted lustre-client [ 6895.077931] Lustre: lustre-MDT0001-mdc-ffff9bda467fd000: Connection to lustre-MDT0001 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6897.926579] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 6898.280370] LustreError: 282632:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda467fd000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6898.286118] LustreError: 282632:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6898.303988] Lustre: Unmounted lustre-client [ 6909.746489] Key type lgssc unregistered [ 6909.909322] LNet: 283025:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6910.951034] LNet: Removed LNI 192.168.204.45@tcp [ 6911.288553] Key type .llcrypt unregistered [ 6911.290168] Key type ._llcrypt unregistered [ 6917.943702] Key type ._llcrypt registered [ 6917.945269] Key type .llcrypt registered [ 6918.430628] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 20:51:21 (1755046281) [ 6925.554657] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6925.590181] alg: No test for adler32 (adler32-zlib) [ 6926.462755] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 6926.562176] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6926.564771] LNet: Accept secure, port 988 [ 6928.159238] Key type lgssc registered [ 6928.782307] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6973.635527] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6974.823597] Lustre: Mounted lustre-client [ 6996.979631] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7007.275336] LustreError: 288357:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 7007.299171] Lustre: Unmounted lustre-client [ 7033.429080] Key type lgssc unregistered [ 7033.579659] LNet: 288757:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7034.597632] LNet: Removed LNI 192.168.204.45@tcp [ 7034.869817] Key type .llcrypt unregistered [ 7034.870897] Key type ._llcrypt unregistered [ 7041.925890] Key type ._llcrypt registered [ 7041.926978] Key type .llcrypt registered [ 7042.564333] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 20:53:25 (1755046405) [ 7043.171692] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [ 7043.879928] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 20:53:26 (1755046406) [ 7044.628595] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [ 7045.409999] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 20:53:28 (1755046408) [ 7046.188389] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [ 7046.919395] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 20:53:29 (1755046409) [ 7047.437288] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [ 7048.075703] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 20:53:30 (1755046410) [ 7048.596938] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [ 7049.374002] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 20:53:32 (1755046412) [ 7053.255510] Key type .llcrypt unregistered [ 7053.257597] Key type ._llcrypt unregistered [ 7055.639296] Key type ._llcrypt registered [ 7055.640405] Key type .llcrypt registered [ 7055.876693] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7055.881937] alg: No test for adler32 (adler32-zlib) [ 7056.780344] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 7056.879036] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 7056.881176] LNet: Accept secure, port 988 [ 7058.479102] Key type lgssc registered [ 7058.951768] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7091.604197] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7093.164093] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7101.780352] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7108.064389] Lustre: Mounted lustre-client [ 7108.622353] LustreError: 294336:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda78438800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7108.630319] LustreError: 294336:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7108.665584] Lustre: Unmounted lustre-client [ 7133.628643] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 20:54:56 (1755046496) [ 7146.325386] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7148.035707] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7156.082728] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7166.448563] Lustre: Mounted lustre-client [ 7166.780911] LustreError: 296449:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda44525000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7166.787625] LustreError: 296449:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7166.789170] LustreError: 296449:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 7166.807340] Lustre: Unmounted lustre-client [ 7183.878549] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 20:55:46 (1755046546) [ 7222.491207] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7224.265309] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7232.487344] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7278.703222] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 20:57:21 (1755046641) [ 7307.752862] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 20:57:50 (1755046670) [ 7324.700451] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7326.421860] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7336.680587] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7342.079312] Lustre: Mounted lustre-client [ 7369.524563] Lustre: Mounted fs15246-client [ 7376.311396] LustreError: 306918:0:(lov_obd.c:784:lov_cleanup()) fs15246-clilov-ffff9bda60079000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7376.320991] LustreError: 306918:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 7376.324539] LustreError: 306918:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 7376.344206] Lustre: Unmounted fs15246-client [ 7391.905853] LustreError: 307136:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9bda467d6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7391.912148] LustreError: 307136:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7391.915433] LustreError: 307136:0:(obd_class.h:479:obd_check_dev()) Skipped 3 previous similar messages [ 7391.939183] Lustre: Unmounted lustre-client [ 7411.136309] Key type lgssc unregistered [ 7411.280075] LNet: 307538:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7412.327977] LNet: Removed LNI 192.168.204.45@tcp [ 7412.712750] Key type .llcrypt unregistered [ 7412.714414] Key type ._llcrypt unregistered [ 7545.152093] Key type ._llcrypt registered [ 7545.154051] Key type .llcrypt registered [ 7545.838899] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 21:01:48 (1755046908) [ 7546.641894] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7546.649925] alg: No test for adler32 (adler32-zlib) [ 7547.569744] Lustre: Lustre: Build Version: 2.16.56_30_ga643f08 [ 7547.694690] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 7547.697963] LNet: Accept secure, port 988 [ 7549.311196] Key type lgssc registered [ 7549.952716] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7611.755703] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7613.589379] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7623.531480] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7685.050605] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 7556 sec ========== 21:04:07 (1755047047) [ 7685.934674] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 21:04:08 (1755047048) === [ 7687.235255] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 21:04:09 (1755047049) === [ 7702.712466] Key type lgssc unregistered [ 7702.899931] LNet: 316699:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7702.912242] LNet: Removed LNI 192.168.204.45@tcp [ 7703.317643] Key type .llcrypt unregistered [ 7703.319697] Key type ._llcrypt unregistered