[ 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 425315187 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, 524588K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001016] APIC: Switch to symmetric I/O mode setup [ 0.002292] x2apic enabled [ 0.003005] Switched APIC routing to physical x2apic. [ 0.004008] kvm-guest: setup PV IPIs [ 0.006372] ..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.007031] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.008017] pid_max: default: 32768 minimum: 301 [ 0.009188] LSM: Security Framework initializing [ 0.011046] Yama: becoming mindful. [ 0.012027] SELinux: Initializing. [ 0.013053] *** VALIDATE selinux *** [ 0.019629] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.023662] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.024125] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.025091] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.026134] *** VALIDATE tmpfs *** [ 0.027432] *** VALIDATE proc *** [ 0.028202] *** VALIDATE cgroup *** [ 0.029006] *** VALIDATE cgroup2 *** [ 0.030291] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.031147] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.032004] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.033027] Spectre V2 : User space: Vulnerable [ 0.034005] Speculative Store Bypass: Vulnerable [ 0.037015] debug: unmapping init [mem 0xffffffff98e59000-0xffffffff98e60fff] [ 0.040000] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.040754] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.041030] ... version: 2 [ 0.042010] ... bit width: 48 [ 0.043009] ... generic registers: 4 [ 0.043912] ... value mask: 0000ffffffffffff [ 0.044009] ... max period: 00007fffffffffff [ 0.045005] ... fixed-purpose events: 3 [ 0.045765] ... event mask: 000000070000000f [ 0.046410] rcu: Hierarchical SRCU implementation. [ 0.048132] smp: Bringing up secondary CPUs ... [ 0.049480] x86: Booting SMP configuration: [ 0.050013] .... node #0, CPUs: #1 #2 #3 [ 0.053112] smp: Brought up 1 node, 4 CPUs [ 0.055007] smpboot: Max logical packages: 1 [ 0.056011] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.226803] node 0 deferred pages initialised in 170ms [ 0.230103] devtmpfs: initialized [ 0.231263] x86/mm: Memory block size: 128MB [ 0.233731] gcov: version magic: 0x41383552 [ 0.236269] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.239080] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.241305] pinctrl core: initialized pinctrl subsystem [ 0.243136] [ 0.243563] ************************************************************* [ 0.245009] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.247009] ** ** [ 0.249009] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.251009] ** ** [ 0.253010] ** This means that this kernel is built to expose internal ** [ 0.254012] ** IOMMU data structures, which may compromise security on ** [ 0.256011] ** your system. ** [ 0.258008] ** ** [ 0.260010] ** If you see this message and you are not debugging the ** [ 0.262008] ** kernel, report this immediately to your vendor! ** [ 0.264008] ** ** [ 0.265009] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.267011] ************************************************************* [ 0.269672] NET: Registered protocol family 16 [ 0.271443] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.274041] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.276052] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.279129] cpuidle: using governor menu [ 0.280579] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.282626] PCI: Using configuration type 1 for base access [ 0.284161] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.292129] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.295037] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.297138] cryptd: max_cpu_qlen set to 1000 [ 0.299280] ACPI: Added _OSI(Module Device) [ 0.301135] ACPI: Added _OSI(Processor Device) [ 0.302011] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.304011] ACPI: Added _OSI(Processor Aggregator Device) [ 0.309057] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.315398] ACPI: Interpreter enabled [ 0.316072] ACPI: PM: (supports S0 S3 S4 S5) [ 0.318017] ACPI: Using IOAPIC for interrupt routing [ 0.320187] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.323624] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.331927] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.334048] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.335015] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.338102] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.342254] acpiphp: Slot [2] registered [ 0.343139] acpiphp: Slot [3] registered [ 0.344059] acpiphp: Slot [4] registered [ 0.345049] acpiphp: Slot [5] registered [ 0.346129] acpiphp: Slot [6] registered [ 0.348118] acpiphp: Slot [7] registered [ 0.349049] acpiphp: Slot [8] registered [ 0.349851] acpiphp: Slot [9] registered [ 0.350041] acpiphp: Slot [10] registered [ 0.352067] acpiphp: Slot [11] registered [ 0.353069] acpiphp: Slot [12] registered [ 0.353866] acpiphp: Slot [13] registered [ 0.354042] acpiphp: Slot [14] registered [ 0.354945] acpiphp: Slot [15] registered [ 0.356075] acpiphp: Slot [16] registered [ 0.356881] acpiphp: Slot [17] registered [ 0.358046] acpiphp: Slot [18] registered [ 0.358924] acpiphp: Slot [19] registered [ 0.359085] acpiphp: Slot [20] registered [ 0.361058] acpiphp: Slot [21] registered [ 0.362050] acpiphp: Slot [22] registered [ 0.363078] acpiphp: Slot [23] registered [ 0.364046] acpiphp: Slot [24] registered [ 0.365058] acpiphp: Slot [25] registered [ 0.366079] acpiphp: Slot [26] registered [ 0.367058] acpiphp: Slot [27] registered [ 0.367969] acpiphp: Slot [28] registered [ 0.369083] acpiphp: Slot [29] registered [ 0.370091] acpiphp: Slot [30] registered [ 0.371066] acpiphp: Slot [31] registered [ 0.372006] PCI host bridge to bus 0000:00 [ 0.372984] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.375022] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.376012] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.378016] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.380019] pci_bus 0000:00: root bus resource [mem 0x180000000-0x1ffffffff window] [ 0.382020] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.383168] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.386155] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.390197] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.395737] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.398575] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.400014] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.402011] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.404015] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.405407] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.407491] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.409019] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.410428] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.413009] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.419696] pci 0000:00:02.0: reg 0x20: [mem 0xfebf4000-0xfebf7fff 64bit pref] [ 0.423451] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.427739] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.431014] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.435008] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.442023] pci 0000:00:05.0: reg 0x20: [mem 0xfebf8000-0xfebfbfff 64bit pref] [ 0.449915] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.455017] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.459015] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.469012] pci 0000:00:06.0: reg 0x20: [mem 0xfebfc000-0xfebfffff 64bit pref] [ 0.475733] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.478277] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.480269] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.482281] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.483132] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.487094] iommu: Default domain type: Passthrough [ 0.489492] SCSI subsystem initialized [ 0.490101] ACPI: bus type USB registered [ 0.490964] usbcore: registered new interface driver usbfs [ 0.492038] usbcore: registered new interface driver hub [ 0.493073] usbcore: registered new device driver usb [ 0.495137] pps_core: LinuxPPS API ver. 1 registered [ 0.496006] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.498080] PTP clock support registered [ 0.500112] EDAC MC: Ver: 3.0.0 [ 0.501344] PCI: Using ACPI for IRQ routing [ 0.502630] NetLabel: Initializing [ 0.503005] NetLabel: domain hash size = 128 [ 0.503921] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.506097] NetLabel: unlabeled traffic allowed by default [ 0.508167] vgaarb: loaded [ 0.509269] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.511012] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.515262] clocksource: Switched to clocksource kvm-clock [ 0.605291] VFS: Disk quotas dquot_6.6.0 [ 0.606526] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.608203] *** VALIDATE ramfs *** [ 0.609227] *** VALIDATE hugetlbfs *** [ 0.611084] pnp: PnP ACPI init [ 0.613067] pnp: PnP ACPI: found 6 devices [ 0.628764] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.631863] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.633836] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.635890] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.638137] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.640372] pci_bus 0000:00: resource 8 [mem 0x180000000-0x1ffffffff window] [ 0.643077] NET: Registered protocol family 2 [ 0.645664] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.650331] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.653689] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.658491] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.661872] TCP: Hash tables configured (established 65536 bind 65536) [ 0.664703] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.667627] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.669635] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.671795] NET: Registered protocol family 1 [ 0.674599] RPC: Registered named UNIX socket transport module. [ 0.676412] RPC: Registered udp transport module. [ 0.678337] RPC: Registered tcp transport module. [ 0.679860] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.682780] NET: Registered protocol family 44 [ 0.684295] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.686214] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.688181] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.690359] PCI: CLS 0 bytes, default 64 [ 0.692625] Unpacking initramfs... [ 2.017732] debug: unmapping init [mem 0xffff942a7cc64000-0xffff942a7ffcffff] [ 2.021636] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.023869] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.027117] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.562764] Initialise system trusted keyrings [ 2.564172] Key type blacklist registered [ 2.567295] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.576395] zbud: loaded [ 2.580167] *** VALIDATE nfs *** [ 2.581232] *** VALIDATE nfs4 *** [ 2.582366] pstore: using deflate compression [ 2.585848] Platform Keyring initialized [ 2.743768] NET: Registered protocol family 38 [ 2.747493] Key type asymmetric registered [ 2.751095] Asymmetric key parser 'x509' registered [ 2.754872] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.761532] io scheduler mq-deadline registered [ 2.763224] io scheduler kyber registered [ 2.765180] io scheduler bfq registered [ 2.770421] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.778493] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.783635] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.786724] ACPI: Power Button [PWRF] [ 2.904457] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.009455] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.105597] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.137408] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.174489] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.180521] Non-volatile memory driver v1.3 [ 3.182185] Linux agpgart interface v0.103 [ 3.213657] virtio_blk virtio1: [vda] 133056 512-byte logical blocks (68.1 MB/65.0 MiB) [ 3.217426] vda: detected capacity change from 0 to 68124672 [ 3.236268] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.239571] vdb: detected capacity change from 0 to 1073741824 [ 3.250227] libphy: Fixed MDIO Bus: probed [ 3.263980] usbcore: registered new interface driver usbserial_generic [ 3.266263] usbserial: USB Serial support registered for generic [ 3.269550] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.273667] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.275534] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.281962] mousedev: PS/2 mouse device common for all mice [ 3.286414] rtc_cmos 00:05: RTC can wake from S4 [ 3.289615] rtc_cmos 00:05: registered as rtc0 [ 3.291565] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.296557] intel_pstate: CPU model not supported [ 3.298217] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.303150] hid: raw HID events driver (C) Jiri Kosina [ 3.305671] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.307871] usbcore: registered new interface driver usbhid [ 3.312860] usbhid: USB HID core driver [ 3.314272] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.317596] drop_monitor: Initializing network drop monitor service [ 3.318901] Initializing XFRM netlink socket [ 3.332276] NET: Registered protocol family 10 [ 3.336321] Segment Routing with IPv6 [ 3.337979] NET: Registered protocol family 17 [ 3.341047] mpls_gso: MPLS GSO support [ 3.357388] RAS: Correctable Errors collector initialized. [ 3.367299] AVX version of gcm_enc/dec engaged. [ 3.369018] AES CTR mode by8 optimization enabled [ 3.551812] sched_clock: Marking stable (3551791369, 0)->(4321145177, -769353808) [ 3.557587] registered taskstats version 1 [ 3.563913] Loading compiled-in X.509 certificates [ 3.566393] zswap: loaded using pool lzo/zbud [ 3.602517] Key type big_key registered [ 3.622802] Key type encrypted registered [ 3.627885] ima: No TPM chip found, activating TPM-bypass! [ 3.629564] ima: Allocated hash algorithm: sha1 [ 3.631098] ima: No architecture policies found [ 3.634384] evm: Initialising EVM extended attributes: [ 3.636430] evm: security.selinux [ 3.637645] evm: security.ima [ 3.638821] evm: security.capability [ 3.640707] evm: HMAC attrs: 0x1 [ 3.645756] rtc_cmos 00:05: setting system clock to 2025-09-02 10:33:46 UTC (1756809226) [ 3.653192] debug: unmapping init [mem 0xffffffff99e03000-0xffffffff99ffffff] [ 3.655951] debug: unmapping init [mem 0xffffffff98b82000-0xffffffff98e58fff] [ 3.664367] Write protecting the kernel read-only data: 28672k [ 3.667795] debug: unmapping init [mem 0xffffffff97203000-0xffffffff973fffff] [ 3.676509] debug: unmapping init [mem 0xffffffff97b14000-0xffffffff97bfffff] [ 3.756706] 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.768317] systemd[1]: Detected virtualization kvm. [ 3.769922] systemd[1]: Detected architecture x86-64. [ 3.776273] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.834754] systemd[1]: No hostname configured. [ 3.837655] systemd[1]: Set hostname to . [ 3.841071] random: systemd: uninitialized urandom read (16 bytes read) [ 3.845085] systemd[1]: Initializing machine ID from random generator. [ 4.107250] hrtimer: interrupt took 11946090 ns [ 4.131944] random: systemd: uninitialized urandom read (16 bytes read) [ 4.135619] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 4.143712] random: systemd: uninitialized urandom read (16 bytes read) [ 4.146581] systemd[1]: Reached target Local Encrypted Volumes. [ OK ] Reached target Local Encrypted Volumes. [ 4.158849] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Paths. [ OK ] Reached target Slices. [ OK ] Reached target Timers. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Local File Systems. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket. Starting Setup Virtual Console... Starting Create list of required st…ce nodes for the current kernel... Starting Journal Service... [ OK ] Reached target Sockets. [ OK ] Reached target Swap. Starting Apply Kernel Variables... Starting Create Volatile Files and Directories... [ OK ] Started Memstrack Anylazing Service. [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Journal Service. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 5.449921] device-mapper: uevent: version 1.0.3 [ 5.453354] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 7.255521] virtio_net virtio0 ens2: renamed from eth0 [ 7.819057] scsi host0: ata_piix [ 7.828188] scsi host1: ata_piix [ 7.858493] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 7.876756] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 12.173250] random: crng init done [ 12.183519] random: 7 urandom warning(s) missed due to ratelimiting [ 14.691522] dracut-initqueue[578]: 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... [ 17.300532] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. Stopping Hardware RNG Entropy Gatherer Daemon... [ 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 target Timers. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 20.508482] printk: systemd: 24 output lines suppressed due to ratelimiting [ 21.222487] SELinux: Disabled at runtime. [ 21.312201] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 21.327474] systemd[1]: Detected virtualization kvm. [ 21.334564] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 22.727167] systemd[1]: initrd-switch-root.service: Succeeded. [ 22.732224] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 22.738361] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 22.743843] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 22.750877] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 22.768126] systemd[1]: Starting Journal Service... Starting Journal Service... [ 22.782531] systemd[1]: Created slice User and Session Slice. [ OK ] Created slice User and Session Slice. [ OK ] Reached target rpc_pipefs.target. [ OK ] Stopped target Switch Root. [ OK ] Created slice system-getty.slice. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Listening on Process Core Dump Socket. [ OK ] Stopped target Initrd Root File System. Activating swap /dev/disk/by-label/SWAP... [ OK ] Reached target RPC Port Mapper. [ OK ] Created slice system-sshd\x2dkeygen.slice. Mounting Huge Pages File System... [ OK ] Created slice system-serial\x2dgetty.slice. Mounting Kernel Debug File System... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ 23.116364] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on udev Kernel Socket. [ OK ] Stopped target Initrd File Systems. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Slices. Starting Remount Root and Kernel File Systems... Starting Apply Kernel Variables... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on initctl Compatibility Named Pipe. Mounting POSIX Message Queue File System... [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Started Journal Service. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted POSIX Message Queue File System. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... [ OK ] Mounted /mnt. [ 24.780170] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Coldplug all Devices. [ OK ] Started udev Kernel Device Manager. [ 26.244425] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 26.507930] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 26.948818] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 27.206852] EDAC sbridge: Ver: 1.1.2 [ 30.637840] Key type dns_resolver registered [* ] A start job is running for Configur…-only root support (8s / no limit)[ 31.406119] NFS: Registering the id_resolver key type [ 31.408432] Key type id_resolver registered [ 31.410026] Key type id_legacy registered [** ] A start job is running for Configur…-only root support (9s / no limit) [*** ] A start job is running for Configur…-only root support (9s / no limit) [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Load/Save Random Seed... Starting Create Volatile Files and Directories... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started dnf makecache --timer. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Started irqbalance daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... Starting Login Service... [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Started Login Service. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg221-client login: [ 84.521647] libcfs: loading out-of-tree module taints kernel. [ 84.534106] Key type ._llcrypt registered [ 84.535682] Key type .llcrypt registered [ 84.760524] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 84.767122] alg: No test for adler32 (adler32-zlib) [ 85.797864] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 86.129605] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 87.768253] Key type lgssc registered [ 88.516987] Lustre: Echo OBD driver; http://www.lustre.org/ [ 168.742793] Lustre: Mounted lustre-client [ 171.614062] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 183.572548] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing check_logdir /tmp/testlogs/ [ 185.918246] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing yml_node [ 188.689840] Lustre: DEBUG MARKER: Client: 2.16.58.1 [ 189.997887] Lustre: DEBUG MARKER: MDS: 2.16.58.1 [ 191.275320] Lustre: DEBUG MARKER: OSS: 2.16.58.1 [ 192.366246] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Tue Sep 2 06:36:54 EDT 2025 [ 194.528188] Lustre: lustre-OST0000-osc-ffff942ac2a90000: disconnect after 24s idle [ 202.777981] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 203.814367] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 205.154664] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 215.411824] LustreError: 8973:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 215.437734] Lustre: Unmounted lustre-client [ 290.511291] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 292.518829] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 305.945327] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 330.653573] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 06:39:12 (1756809552) [ 331.968314] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 332.878955] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 06:39:15 (1756809555) [ 348.497625] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 350.267753] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 359.820591] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 365.570461] Lustre: Mounted lustre-client [ 371.400941] LustreError: 14406:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac71e8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 371.412471] LustreError: 14406:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 371.415224] LustreError: 14406:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 371.449051] Lustre: Unmounted lustre-client [ 380.375164] Key type lgssc unregistered [ 380.542367] LNet: 14811:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 380.546779] LNetError: 14811:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 380.560737] LNet: Removed LNI 192.168.202.21@tcp [ 380.958191] Key type .llcrypt unregistered [ 380.959845] Key type ._llcrypt unregistered [ 387.720213] Key type ._llcrypt registered [ 387.721972] Key type .llcrypt registered [ 388.293078] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 06:40:10 (1756809610) [ 388.800158] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 388.840663] alg: No test for adler32 (adler32-zlib) [ 389.733197] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 389.841809] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 391.440961] Key type lgssc registered [ 391.993408] Lustre: Echo OBD driver; http://www.lustre.org/ [ 430.047916] Lustre: Mounted lustre-client [ 507.586436] LustreError: 18115:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac9332800: lov tgt 1990 not cleaned! deathrow=0, lovrc=1 [ 507.604400] LustreError: 18115:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 507.618034] Lustre: Unmounted lustre-client [ 524.271387] Key type lgssc unregistered [ 524.403492] LNet: 18578:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 524.406765] LNetError: 18578:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 524.419618] LNet: Removed LNI 192.168.202.21@tcp [ 524.728605] Key type .llcrypt unregistered [ 524.730106] Key type ._llcrypt unregistered [ 530.099849] Key type ._llcrypt registered [ 530.101129] Key type .llcrypt registered [ 530.680456] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 06:42:32 (1756809752) [ 531.542117] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 531.579060] alg: No test for adler32 (adler32-zlib) [ 532.465965] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 532.562156] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 534.152109] Key type lgssc registered [ 534.633972] Lustre: Echo OBD driver; http://www.lustre.org/ [ 558.219881] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 560.155287] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 569.473977] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 576.002941] Lustre: Mounted lustre-client [ 581.097640] LustreError: 21679:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac59eb800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 581.111912] LustreError: 21679:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 581.139492] Lustre: Unmounted lustre-client [ 597.085832] Key type lgssc unregistered [ 597.219636] LNet: 22080:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 597.223099] LNetError: 22080:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 597.233110] LNet: Removed LNI 192.168.202.21@tcp [ 597.539116] Key type .llcrypt unregistered [ 597.540745] Key type ._llcrypt unregistered [ 602.015767] Key type ._llcrypt registered [ 602.017856] Key type .llcrypt registered [ 602.639318] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 06:43:44 (1756809824) [ 603.689974] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 603.696667] alg: No test for adler32 (adler32-zlib) [ 604.597928] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 604.701231] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 606.304187] Key type lgssc registered [ 606.842021] Lustre: Echo OBD driver; http://www.lustre.org/ [ 631.121519] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 632.781764] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 641.421333] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 648.182576] Lustre: Mounted lustre-client [ 651.217361] LustreError: 24772:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ad81b9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 651.228416] LustreError: 24772:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 651.252116] Lustre: Unmounted lustre-client [ 669.862242] Key type lgssc unregistered [ 669.994637] LNet: 25175:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 669.998655] LNetError: 25175:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 670.009722] LNet: Removed LNI 192.168.202.21@tcp [ 670.297363] Key type .llcrypt unregistered [ 670.299144] Key type ._llcrypt unregistered [ 676.420688] Key type ._llcrypt registered [ 676.422194] Key type .llcrypt registered [ 676.990764] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 06:44:59 (1756809899) [ 677.988388] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 677.997180] alg: No test for adler32 (adler32-zlib) [ 678.886625] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 678.989720] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 680.592281] Key type lgssc registered [ 681.061748] Lustre: Echo OBD driver; http://www.lustre.org/ [ 701.764880] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 703.179781] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 711.402270] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 721.925871] Lustre: Mounted lustre-client [ 727.017612] Lustre: lustre-MDT0000-mdc-ffff942ac8735000: Connection to lustre-MDT0000 (at 192.168.202.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 737.253301] Lustre: lustre-MDT0001-mdc-ffff942ac8735000: Connection to lustre-MDT0001 (at 192.168.202.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 739.660758] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 747.488157] Lustre: 26129:0:(client.c:2470:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1756809954/real 1756809954] req@0000000075abf379 x1842148698757248/t0(0) o400->MGC192.168.202.121@tcp@192.168.202.121@tcp:26/25 lens 224/224 e 0 to 1 dl 1756809970 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 747.499501] LustreError: MGC192.168.202.121@tcp: Connection to MGS (at 192.168.202.121@tcp) was lost; in progress operations using this service will fail [ 749.934096] LustreError: 28345:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 749.969436] Lustre: Unmounted lustre-client [ 761.852352] Key type lgssc unregistered [ 761.989753] LNet: 28736:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 761.993448] LNetError: 28736:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 762.003677] LNet: Removed LNI 192.168.202.21@tcp [ 762.290417] Key type .llcrypt unregistered [ 762.292886] Key type ._llcrypt unregistered [ 768.805196] Key type ._llcrypt registered [ 768.806808] Key type .llcrypt registered [ 769.406420] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 06:46:31 (1756809991) [ 770.440901] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 770.452783] alg: No test for adler32 (adler32-zlib) [ 771.340382] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 771.446003] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 773.048203] Key type lgssc registered [ 773.520484] Lustre: Echo OBD driver; http://www.lustre.org/ [ 794.512378] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 796.127431] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 804.012674] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 810.504353] Lustre: Mounted lustre-client [ 815.596383] Lustre: lustre-OST0000-osc-ffff942ac792e000: Connection to lustre-OST0000 (at 192.168.202.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 821.471924] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 831.807644] LustreError: 31850:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 831.853523] Lustre: Unmounted lustre-client [ 861.156745] Key type lgssc unregistered [ 861.523147] LNet: 32251:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 861.533249] LNetError: 32251:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 861.555698] LNet: Removed LNI 192.168.202.21@tcp [ 862.666558] Key type .llcrypt unregistered [ 862.667970] Key type ._llcrypt unregistered [ 881.070816] Key type ._llcrypt registered [ 881.072414] Key type .llcrypt registered [ 881.995656] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 06:48:23 (1756810103) [ 883.507756] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 883.579718] alg: No test for adler32 (adler32-zlib) [ 884.532236] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 884.700073] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 886.368169] Key type lgssc registered [ 887.281030] Lustre: Echo OBD driver; http://www.lustre.org/ [ 916.949638] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 919.016299] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 930.488227] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 932.953946] Lustre: Mounted lustre-client [ 939.346892] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 941.664736] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 952.984170] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2025-09-02 6h49m34s [ 954.164391] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 958.447375] Lustre: lustre-MDT0000-mdc-ffff942ac7929800: Connection to lustre-MDT0000 (at 192.168.202.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 962.902527] LustreError: 35949:0:(lmv_obd.c:1434:lmv_statfs()) lustre-MDT0000-mdc-ffff942ac7929800: can't stat MDS #0: rc = -19 [ 963.878780] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 969.702521] LustreError: MGC192.168.202.121@tcp: Connection to MGS (at 192.168.202.121@tcp) was lost; in progress operations using this service will fail [ 969.722183] Lustre: Evicted from MGS (at 192.168.202.121@tcp) after server handle changed from 0xbd038ff1f754c6a to 0xbd038ff1f754f26 [ 969.730469] Lustre: MGC192.168.202.121@tcp: Connection restored to 192.168.202.121@tcp (at 192.168.202.121@tcp) [ 970.790453] LustreError: lustre-MDT0000-mdc-ffff942ac7929800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 970.802609] Lustre: lustre-MDT0000-mdc-ffff942ac7929800: Connection restored to 192.168.202.121@tcp (at 192.168.202.121@tcp) [ 972.715242] Lustre: DEBUG MARKER: Wait for df (35949) ... [ 973.682319] Lustre: DEBUG MARKER: done [ 974.660095] LustreError: 36558:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac7929800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 974.668643] LustreError: 36558:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 974.730587] Lustre: Unmounted lustre-client [ 994.152890] Key type lgssc unregistered [ 994.313404] LNet: 36959:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 994.317632] LNetError: 36959:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 994.329841] LNet: Removed LNI 192.168.202.21@tcp [ 994.663132] Key type .llcrypt unregistered [ 994.664725] Key type ._llcrypt unregistered [ 1019.808357] Key type ._llcrypt registered [ 1019.810180] Key type .llcrypt registered [ 1020.117664] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1020.128192] alg: No test for adler32 (adler32-zlib) [ 1021.117963] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 1021.302514] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 1022.944186] Key type lgssc registered [ 1023.684441] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1074.751873] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1077.294269] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1091.830181] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1122.663258] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 06:52:24 (1756810344) [ 1144.684682] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1147.835143] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1160.960606] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1177.106940] Lustre: Mounted lustre-client [ 1180.575814] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2025-09-02 6h53m22s [ 1181.579865] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1186.479905] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1188.832318] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1209.312303] Lustre: 43818:0:(client.c:2470:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1756810411/real 1756810411] req@00000000639160c6 x1842149058418944/t0(0) o101->lustre-MDT0000-mdc-ffff942ac68dc000@192.168.202.121@tcp:12/10 lens 576/1152 e 0 to 1 dl 1756810431 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:0 [ 1209.331790] Lustre: lustre-MDT0000-mdc-ffff942ac68dc000: Connection to lustre-MDT0000 (at 192.168.202.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1209.356753] Lustre: lustre-MDT0000-mdc-ffff942ac68dc000: Connection restored to 192.168.202.121@tcp (at 192.168.202.121@tcp) [ 1230.000388] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1231.941495] Lustre: DEBUG MARKER: done [ 1233.920994] LustreError: 44299:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac68dc000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1233.938785] LustreError: 44299:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1233.969972] Lustre: Unmounted lustre-client [ 1264.965880] Key type lgssc unregistered [ 1265.194691] LNet: 44701:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1265.209921] LNetError: 44701:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1265.227563] LNet: Removed LNI 192.168.202.21@tcp [ 1265.826533] Key type .llcrypt unregistered [ 1265.829399] Key type ._llcrypt unregistered [ 1291.431166] Key type ._llcrypt registered [ 1291.434078] Key type .llcrypt registered [ 1291.676950] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1291.683777] alg: No test for adler32 (adler32-zlib) [ 1292.604778] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 1292.757483] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 1294.384193] Key type lgssc registered [ 1295.044488] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1339.244812] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1341.244488] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1351.023317] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1376.084451] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 06:56:38 (1756810598) [ 1376.887744] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1377.884956] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 06:56:39 (1756810599) [ 1391.882214] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 06:56:53 (1756810613) [ 1408.341724] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1410.586336] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1422.480741] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1423.891710] Lustre: Mounted lustre-client [ 1429.963752] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 1431.989841] LustreError: 52518:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ada6f9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1432.007027] LustreError: 52518:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1432.062109] Lustre: Unmounted lustre-client [ 1440.674539] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 1475.958898] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1477.883699] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1478.199372] Lustre: Mounted lustre-client [ 1479.827278] LustreError: 53779:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac59ee000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1479.844498] LustreError: 53779:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1479.849026] LustreError: 53779:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 1479.881807] Lustre: Unmounted lustre-client [ 1528.286075] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1530.506111] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1530.888925] Lustre: Mounted lustre-client [ 1532.841577] LustreError: 54897:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ada625000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1532.863084] LustreError: 54897:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1532.870589] LustreError: 54897:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 1532.929175] Lustre: Unmounted lustre-client [ 1546.979242] Lustre: DEBUG MARKER: files compared the same [ 1563.766712] Key type lgssc unregistered [ 1563.918522] LNet: 55601:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1563.922180] LNetError: 55601:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1563.936557] LNet: Removed LNI 192.168.202.21@tcp [ 1564.286783] Key type .llcrypt unregistered [ 1564.289301] Key type ._llcrypt unregistered [ 1571.100659] Key type ._llcrypt registered [ 1571.102301] Key type .llcrypt registered [ 1571.754366] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 06:59:53 (1756810793) [ 1572.844401] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1572.855285] alg: No test for adler32 (adler32-zlib) [ 1573.767346] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 1573.879810] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 1575.496308] Key type lgssc registered [ 1576.079528] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1603.709986] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1605.358527] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1613.871915] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1619.459597] Lustre: Mounted lustre-client [ 1621.372158] LustreError: 58683:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac5f5d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1621.389872] LustreError: 58683:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1621.431595] Lustre: Unmounted lustre-client [ 1649.609360] Key type lgssc unregistered [ 1649.765877] LNet: 59098:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1649.771802] LNetError: 59098:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1649.781987] LNet: Removed LNI 192.168.202.21@tcp [ 1650.120184] Key type .llcrypt unregistered [ 1650.121627] Key type ._llcrypt unregistered [ 1658.242208] Key type ._llcrypt registered [ 1658.244094] Key type .llcrypt registered [ 1658.909436] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 07:01:21 (1756810881) [ 1660.034603] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1660.094660] alg: No test for adler32 (adler32-zlib) [ 1661.046814] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 1661.174215] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 1662.792178] Key type lgssc registered [ 1663.485951] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1733.471588] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1746.885291] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1748.396933] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1767.238244] Key type lgssc unregistered [ 1767.378770] LNet: 62482:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1767.382410] LNetError: 62482:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1767.392616] LNet: Removed LNI 192.168.202.21@tcp [ 1767.680731] Key type .llcrypt unregistered [ 1767.682434] Key type ._llcrypt unregistered [ 1773.828354] Key type ._llcrypt registered [ 1773.829995] Key type .llcrypt registered [ 1774.453282] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 07:03:16 (1756810996) [ 1775.781553] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1775.788450] alg: No test for adler32 (adler32-zlib) [ 1776.708706] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 1776.810351] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 1778.416395] Key type lgssc registered [ 1778.912413] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1811.416944] Lustre: Mounted lustre-client [ 1816.996503] LustreError: 64970:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac37a1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 1817.010146] LustreError: 64970:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 1817.038333] Lustre: Unmounted lustre-client [ 1839.453259] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 07:04:21 (1756811061) [ 1886.146334] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1897.971417] Lustre: Mounted lustre-client [ 1903.336765] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1903.340330] LustreError: 68917:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff942ac4116000: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 1903.350200] Lustre: 63460:0:(llite_lib.c:4239:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.202.121@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 1913.645350] LustreError: 68917:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 1913.648269] LustreError: 68917:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 1913.664413] Lustre: Unmounted lustre-client [ 1950.817849] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 07:06:13 (1756811173) [ 1955.636314] Key type lgssc unregistered [ 1955.781164] LNet: 70126:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1955.784437] LNetError: 70126:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1955.794945] LNet: Removed LNI 192.168.202.21@tcp [ 1956.120713] Key type .llcrypt unregistered [ 1956.122216] Key type ._llcrypt unregistered [ 1961.230308] Key type ._llcrypt registered [ 1961.233882] Key type .llcrypt registered [ 1961.415919] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1961.422825] alg: No test for adler32 (adler32-zlib) [ 1962.303841] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 1962.400699] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 1964.000222] Key type lgssc registered [ 1964.527538] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1987.018974] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1988.531027] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 1996.781309] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2002.437255] Lustre: Mounted lustre-client [ 2004.430782] LustreError: 72643:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac81a3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2004.448410] LustreError: 72643:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2004.484398] Lustre: Unmounted lustre-client [ 2023.452585] Key type lgssc unregistered [ 2023.587330] LNet: 73045:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2023.590900] LNetError: 73045:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2023.600643] LNet: Removed LNI 192.168.202.21@tcp [ 2023.880655] Key type .llcrypt unregistered [ 2023.882255] Key type ._llcrypt unregistered [ 2028.107258] Key type ._llcrypt registered [ 2028.111986] Key type .llcrypt registered [ 2028.329282] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2028.349605] alg: No test for adler32 (adler32-zlib) [ 2029.230927] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 2029.323869] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 2030.920176] Key type lgssc registered [ 2031.383302] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2077.521743] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2079.037537] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2087.151464] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2097.664226] Lustre: Mounted lustre-client [ 2098.268160] LustreError: 75975:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ae1892800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2098.277890] LustreError: 75975:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2098.309113] Lustre: Unmounted lustre-client [ 2116.752556] Key type lgssc unregistered [ 2116.884750] LNet: 76377:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2116.888557] LNetError: 76377:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2116.896930] LNet: Removed LNI 192.168.202.21@tcp [ 2117.177965] Key type .llcrypt unregistered [ 2117.179521] Key type ._llcrypt unregistered [ 2123.149423] Key type ._llcrypt registered [ 2123.151118] Key type .llcrypt registered [ 2123.711301] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 07:09:05 (1756811345) [ 2124.674592] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2124.684826] alg: No test for adler32 (adler32-zlib) [ 2125.555901] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 2125.649268] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 2127.240167] Key type lgssc registered [ 2127.683118] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2147.193747] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2148.662119] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2155.608519] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2166.265601] Lustre: Mounted lustre-client [ 2168.926962] LustreError: 79491:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac4115800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2168.940331] LustreError: 79491:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2168.978909] Lustre: Unmounted lustre-client [ 2169.212590] Lustre: Mounted lustre-client [ 2170.004446] LustreError: 79567:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac59e8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2170.011773] LustreError: 79567:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2170.014880] LustreError: 79567:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 2170.043157] Lustre: Unmounted lustre-client [ 2188.416339] Key type lgssc unregistered [ 2188.545652] LNet: 79969:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2188.549130] LNetError: 79969:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2188.556692] LNet: Removed LNI 192.168.202.21@tcp [ 2188.827193] Key type .llcrypt unregistered [ 2188.828632] Key type ._llcrypt unregistered [ 2193.817841] Key type ._llcrypt registered [ 2193.819387] Key type .llcrypt registered [ 2194.009678] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2194.018301] alg: No test for adler32 (adler32-zlib) [ 2194.932335] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 2195.051507] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 2196.680238] Key type lgssc registered [ 2197.222604] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2218.167342] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2219.569314] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2227.358511] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2237.938315] Lustre: Mounted lustre-client [ 2239.959791] LustreError: 82501:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac87c0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2239.970563] LustreError: 82501:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2239.996902] Lustre: Unmounted lustre-client [ 2266.936373] Key type lgssc unregistered [ 2267.079068] LNet: 82903:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2267.082322] LNetError: 82903:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2267.091689] LNet: Removed LNI 192.168.202.21@tcp [ 2267.378405] Key type .llcrypt unregistered [ 2267.380059] Key type ._llcrypt unregistered [ 2273.123145] Key type ._llcrypt registered [ 2273.124564] Key type .llcrypt registered [ 2273.601836] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 07:11:35 (1756811495) [ 2274.393000] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2274.445777] alg: No test for adler32 (adler32-zlib) [ 2275.317421] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 2275.410382] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 2277.008405] Key type lgssc registered [ 2277.463476] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2296.041517] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2297.335528] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2304.398805] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2314.755331] Lustre: Mounted lustre-client [ 2326.524220] Lustre: lustre: nosquash_nids is cleared [ 2326.533886] Lustre: lustre: root_squash is set to 500:500 [ 2339.835251] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.202.21@tcp 192.168.0.[2,10]@tcp [ 2346.997433] Lustre: lustre: nosquash_nids set to 192.168.202.21/24@tcp [ 2348.531421] LustreError: 86403:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ada621800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2348.541604] LustreError: 86403:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2348.565821] Lustre: Unmounted lustre-client [ 2366.491422] Key type lgssc unregistered [ 2366.623591] LNet: 86805:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2366.626942] LNetError: 86805:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2366.637565] LNet: Removed LNI 192.168.202.21@tcp [ 2366.920975] Key type .llcrypt unregistered [ 2366.922634] Key type ._llcrypt unregistered [ 2372.279559] Key type ._llcrypt registered [ 2372.281380] Key type .llcrypt registered [ 2372.771270] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 07:13:15 (1756811595) [ 2373.628874] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 2374.237980] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 07:13:16 (1756811596) [ 2375.013265] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2375.063473] alg: No test for adler32 (adler32-zlib) [ 2375.924669] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 2376.012203] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 2377.592190] Key type lgssc registered [ 2378.024594] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2395.529122] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2396.754490] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2403.203396] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2403.402357] Lustre: lustre: root_squash is set to 500:500 [ 2403.411416] Lustre: lustre: nosquash_nids set to 192.168.202.21/24@tcp [ 2413.554732] Lustre: Mounted lustre-client [ 2415.928431] LustreError: 90259:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac4943000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2415.936734] LustreError: 90259:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 2415.962256] Lustre: Unmounted lustre-client [ 2433.946591] Key type lgssc unregistered [ 2434.073519] LNet: 90660:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2434.075976] LNetError: 90660:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2434.082638] LNet: Removed LNI 192.168.202.21@tcp [ 2434.350090] Key type .llcrypt unregistered [ 2434.351895] Key type ._llcrypt unregistered [ 2440.080039] Key type ._llcrypt registered [ 2440.081714] Key type .llcrypt registered [ 2440.601283] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 2441.224452] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 07:14:23 (1756811663) [ 2447.650730] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2447.663071] alg: No test for adler32 (adler32-zlib) [ 2448.521446] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 2448.604303] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 2450.192163] Key type lgssc registered [ 2450.598839] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2483.340827] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2484.667226] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2492.652236] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2538.924383] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2540.397122] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2547.966542] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2558.621790] Lustre: Mounted lustre-client [ 2570.670916] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff942ad8f71800.ost_server_uuid 50 [ 2571.214465] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff942ad8f71800.ost_server_uuid in FULL state after 0 sec [ 2571.366621] Lustre: Mounted lustre-client [ 2572.260483] LustreError: 97542:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac5964800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2572.268844] LustreError: 97542:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 2572.296532] Lustre: Unmounted lustre-client [ 2600.668882] Key type lgssc unregistered [ 2600.796645] LNet: 98112:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2600.799154] LNetError: 98112:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2600.806469] LNet: Removed LNI 192.168.202.21@tcp [ 2601.049626] Key type .llcrypt unregistered [ 2601.051079] Key type ._llcrypt unregistered [ 2616.141560] Key type ._llcrypt registered [ 2616.143521] Key type .llcrypt registered [ 2616.381847] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2616.389334] alg: No test for adler32 (adler32-zlib) [ 2617.254309] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 2617.354114] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 2618.944215] Key type lgssc registered [ 2619.391534] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2649.751131] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2651.005730] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2659.386609] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2683.099741] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 07:18:25 (1756811905) [ 2715.401520] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2716.553590] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2724.037609] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2725.232134] Lustre: Mounted lustre-client [ 2740.708952] Lustre: lustre-OST0000-osc-ffff942ac7b22000: Connection to lustre-OST0000 (at 192.168.202.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2745.826568] Lustre: lustre-MDT0000-mdc-ffff942ac7b22000: Connection to lustre-MDT0000 (at 192.168.202.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2746.848134] Lustre: 98896:0:(client.c:2470:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1756811953/real 1756811953] req@00000000fee8f4a1 x1842150731945088/t0(0) o400->lustre-OST0000-osc-ffff942ac7b22000@192.168.202.121@tcp:28/4 lens 224/224 e 0 to 1 dl 1756811969 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2751.968141] Lustre: 98896:0:(client.c:2470:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1756811958/real 1756811958] req@00000000e8be519a x1842150731945600/t0(0) o400->lustre-OST0000-osc-ffff942ac7b22000@192.168.202.121@tcp:28/4 lens 224/224 e 0 to 1 dl 1756811974 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2756.066746] LustreError: MGC192.168.202.121@tcp: Connection to MGS (at 192.168.202.121@tcp) was lost; in progress operations using this service will fail [ 2756.074767] Lustre: Evicted from MGS (at 192.168.202.121@tcp) after server handle changed from 0x1b0da13d3073849f to 0x1b0da13d3073873f [ 2756.078295] Lustre: MGC192.168.202.121@tcp: Connection restored to 192.168.202.121@tcp (at 192.168.202.121@tcp) [ 2760.858277] Lustre: lustre-MDT0000-mdc-ffff942ac7b22000: Connection restored to 192.168.202.121@tcp (at 192.168.202.121@tcp) [ 2761.286306] LustreError: 106735:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac7b22000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 2761.295297] LustreError: 106735:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2761.318976] Lustre: Unmounted lustre-client [ 2778.877159] Key type lgssc unregistered [ 2779.004612] LNet: 107136:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2779.006905] LNetError: 107136:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2779.014738] LNet: Removed LNI 192.168.202.21@tcp [ 2779.292873] Key type .llcrypt unregistered [ 2779.294468] Key type ._llcrypt unregistered [ 2784.804813] Key type ._llcrypt registered [ 2784.806093] Key type .llcrypt registered [ 2785.279697] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 07:20:07 (1756812007) [ 2796.958330] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2796.992855] alg: No test for adler32 (adler32-zlib) [ 2797.856247] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 2797.940991] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 2799.520168] Key type lgssc registered [ 2799.939024] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2815.337528] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2849.689991] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2850.977363] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 2858.537133] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2865.114154] Lustre: Mounted lustre-client [ 2865.844117] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 2866.456295] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 07:21:28 (1756812088) [ 2932.033918] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2942.311596] LustreError: 117867:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 2942.324069] Lustre: Unmounted lustre-client [ 3013.026957] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3014.338406] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3022.233222] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3039.941414] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 07:24:22 (1756812262) [ 3071.575567] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3072.762572] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3080.560957] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3080.711508] Lustre: Mounted lustre-client [ 3081.851639] LustreError: 125255:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac36e6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3081.859525] LustreError: 125255:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3081.861231] LustreError: 125255:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 3081.883923] Lustre: Unmounted lustre-client [ 3100.641799] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 07:25:22 (1756812322) [ 3132.684090] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3133.943583] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3141.533895] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3141.693329] Lustre: Mounted lustre-client [ 3142.766278] LustreError: 129290:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ae0186000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3142.774082] LustreError: 129290:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3142.776915] LustreError: 129290:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 3142.800106] Lustre: Unmounted lustre-client [ 3160.176427] Key type lgssc unregistered [ 3160.296540] LNet: 129691:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3160.299159] LNetError: 129691:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3160.309788] LNet: Removed LNI 192.168.202.21@tcp [ 3160.542720] Key type .llcrypt unregistered [ 3160.544082] Key type ._llcrypt unregistered [ 3165.784221] Key type ._llcrypt registered [ 3165.785493] Key type .llcrypt registered [ 3166.274344] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 07:26:28 (1756812388) [ 3167.163751] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3167.186829] alg: No test for adler32 (adler32-zlib) [ 3168.052392] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 3168.147281] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 3169.736116] Key type lgssc registered [ 3170.126166] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3186.991416] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3188.146845] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3194.253866] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3209.715821] Lustre: Mounted lustre-client [ 3220.409815] LustreError: 132785:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ae0185800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3220.417102] LustreError: 132785:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3220.435174] Lustre: Unmounted lustre-client [ 3246.312058] Key type lgssc unregistered [ 3246.424603] LNet: 133188:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3246.427703] LNetError: 133188:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3246.436603] LNet: Removed LNI 192.168.202.21@tcp [ 3246.687018] Key type .llcrypt unregistered [ 3246.687883] Key type ._llcrypt unregistered [ 3251.983197] Key type ._llcrypt registered [ 3251.984271] Key type .llcrypt registered [ 3252.453735] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 07:27:54 (1756812474) [ 3253.298802] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3253.313243] alg: No test for adler32 (adler32-zlib) [ 3254.169655] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 3254.250908] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 3255.832155] Key type lgssc registered [ 3256.194450] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3273.167324] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3274.429818] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3280.946493] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3291.630799] Lustre: Mounted lustre-client [ 3296.741133] Lustre: lustre-OST0000-osc-ffff942ac9160000: Connection to lustre-OST0000 (at 192.168.202.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3310.213437] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff942ac9160000.ost_server_uuid 50 [ 3310.804778] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff942ac9160000.ost_server_uuid in DISCONN state after 0 sec [ 3311.385272] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 3320.726453] LustreError: 136858:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac9160000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3320.730652] LustreError: 136858:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3320.756143] Lustre: Unmounted lustre-client [ 3331.681824] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 07:29:14 (1756812554) [ 3341.384419] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3342.597144] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3348.411370] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3355.400713] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3355.556814] Lustre: Mounted lustre-client [ 3360.740366] Lustre: lustre-OST0000-osc-ffff942ae0150800: Connection to lustre-OST0000 (at 192.168.202.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3375.360573] LustreError: 139504:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ae0150800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3375.366649] LustreError: 139504:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 3375.369795] LustreError: 139504:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 3375.392259] Lustre: Unmounted lustre-client [ 3399.834434] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 07:30:22 (1756812622) [ 3410.436545] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3411.706604] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3418.162982] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3424.593710] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3424.774464] Lustre: Mounted lustre-client [ 3429.859948] Lustre: lustre-OST0000-osc-ffff942ac5962000: Connection to lustre-OST0000 (at 192.168.202.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3441.477332] LustreError: 142254:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac5962000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3441.481967] LustreError: 142254:0:(lov_obd.c:784:lov_cleanup()) Skipped 1 previous similar message [ 3441.487966] LustreError: 142254:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 3441.489566] LustreError: 142254:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 3441.511117] Lustre: Unmounted lustre-client [ 3464.569435] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 07:31:26 (1756812686) [ 3494.923688] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3496.118823] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3503.443217] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3531.858394] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3533.036305] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3539.017359] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3547.882733] Lustre: Mounted lustre-client [ 3575.065668] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3575.609385] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 3575.891721] LustreError: 148832:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac4adf000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3575.895526] LustreError: 148832:0:(lov_obd.c:784:lov_cleanup()) Skipped 1 previous similar message [ 3575.901384] LustreError: 148832:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 3575.903319] LustreError: 148832:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 3575.925094] Lustre: Unmounted lustre-client [ 3594.288551] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 07:33:36 (1756812816) [ 3604.193419] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3605.432262] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3611.277545] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3631.428805] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3644.469735] Lustre: Mounted lustre-client [ 3671.577620] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3672.132588] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 3675.109591] Lustre: lustre-OST0001-osc-ffff942ac87c0000: Connection to lustre-OST0001 (at 192.168.202.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3679.703778] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3689.958963] LustreError: 152401:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 3689.961932] LustreError: 152401:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 3689.978166] Lustre: Unmounted lustre-client [ 3719.295930] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 07:35:41 (1756812941) [ 3728.647531] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3729.763336] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3735.504844] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3740.660203] Lustre: Mounted lustre-client [ 3747.780683] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3749.368556] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 1 sec [ 3753.322622] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff942ae0151000.ost_server_uuid 50 [ 3753.801490] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff942ae0151000.ost_server_uuid in FULL state after 0 sec [ 3754.441018] LustreError: 155820:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ae0151000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3754.446171] LustreError: 155820:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 3754.447703] LustreError: 155820:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 3754.470116] Lustre: Unmounted lustre-client [ 3754.548126] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 3754.549672] Lustre: Skipped 3 previous similar messages [ 3754.933387] LustreError: 155883:0:(sec.c:411:import_sec_validate_get()) import 00000000bf120ae4 (NEW) with no sec [ 3785.704901] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 07:36:48 (1756813008) [ 3795.418548] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3796.519870] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3802.509697] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3808.517987] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3808.669695] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 3808.722753] Lustre: Mounted lustre-client [ 3808.723555] Lustre: Skipped 1 previous similar message [ 3818.393920] LustreError: 158732:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ada721800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3818.397198] LustreError: 158732:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 3818.405982] LustreError: 158732:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 3818.407669] LustreError: 158732:0:(obd_class.h:479:obd_check_dev()) Skipped 18 previous similar messages [ 3818.432596] Lustre: Unmounted lustre-client [ 3818.433617] Lustre: Skipped 1 previous similar message [ 3837.038172] Key type lgssc unregistered [ 3837.155614] LNet: 159178:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3837.158678] LNetError: 159178:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3837.168780] LNet: Removed LNI 192.168.202.21@tcp [ 3837.414940] Key type .llcrypt unregistered [ 3837.416471] Key type ._llcrypt unregistered [ 3842.319127] Key type ._llcrypt registered [ 3842.320319] Key type .llcrypt registered [ 3842.724164] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 07:37:45 (1756813065) [ 3842.997965] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3843.022992] alg: No test for adler32 (adler32-zlib) [ 3843.868069] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 3843.941167] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 3845.520170] Key type lgssc registered [ 3845.911307] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3863.723400] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3865.002077] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3871.155382] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3877.111940] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3877.252074] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 3882.480546] Lustre: Mounted lustre-client [ 3882.963377] LustreError: lustre-MDT0001-mdc-ffff942ac7a51000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 3882.972376] Lustre: lustre-MDT0001-mdc-ffff942ac7a51000: Connection restored to 192.168.202.121@tcp (at 192.168.202.121@tcp) [ 3884.199883] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3884.798870] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 3904.487754] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 3905.667128] LustreError: 163376:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac7a51000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 3905.673047] LustreError: 163376:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 3905.696235] Lustre: Unmounted lustre-client [ 3925.235765] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 07:39:07 (1756813147) [ 3957.083510] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3958.279370] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 3965.817370] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3965.998170] Lustre: Mounted lustre-client [ 3976.113799] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3976.642677] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 3989.989859] Lustre: lustre-OST0001-osc-ffff942ac81a7000: Connection to lustre-OST0001 (at 192.168.202.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4002.910571] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4013.162127] LustreError: 168095:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 4013.165496] LustreError: 168095:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 4013.181133] Lustre: Unmounted lustre-client [ 4053.989616] Key type lgssc unregistered [ 4054.118569] LNet: 168501:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4054.121703] LNetError: 168501:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4054.131793] LNet: Removed LNI 192.168.202.21@tcp [ 4054.389622] Key type .llcrypt unregistered [ 4054.391027] Key type ._llcrypt unregistered [ 4069.268661] Key type ._llcrypt registered [ 4069.277045] Key type .llcrypt registered [ 4069.436832] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4069.441937] alg: No test for adler32 (adler32-zlib) [ 4070.316911] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 4070.412567] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 4071.992131] Key type lgssc registered [ 4072.392778] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4102.528649] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4103.844484] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4111.762977] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4143.765930] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 07:42:46 (1756813366) [ 4154.095184] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4155.287243] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4161.657371] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4172.274953] Lustre: Mounted lustre-client [ 4209.446032] LustreError: 174563:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac4110800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4209.455840] LustreError: 174563:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4209.477337] Lustre: Unmounted lustre-client [ 4226.161075] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4226.313860] Lustre: Mounted lustre-client [ 4258.643401] LustreError: 175230:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ae0154000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4258.651109] LustreError: 175230:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4258.653184] LustreError: 175230:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 4258.675369] Lustre: Unmounted lustre-client [ 4276.302184] Key type lgssc unregistered [ 4276.435657] LNet: 175636:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4276.439542] LNetError: 175636:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4276.449826] LNet: Removed LNI 192.168.202.21@tcp [ 4276.753473] Key type .llcrypt unregistered [ 4276.754471] Key type ._llcrypt unregistered [ 4282.513771] Key type ._llcrypt registered [ 4282.515011] Key type .llcrypt registered [ 4282.981899] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 07:45:05 (1756813505) [ 4283.741073] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4283.761358] alg: No test for adler32 (adler32-zlib) [ 4284.626422] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 4284.708942] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 4286.288107] Key type lgssc registered [ 4286.650558] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4304.210346] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4305.325090] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4311.238754] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4312.401043] Lustre: Mounted lustre-client [ 4318.883218] LustreError: 178882:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ad7e89800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4318.889802] LustreError: 178882:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4318.908703] Lustre: Unmounted lustre-client [ 4336.543609] Key type lgssc unregistered [ 4336.673645] LNet: 179284:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4336.675968] LNetError: 179284:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4336.686295] LNet: Removed LNI 192.168.202.21@tcp [ 4336.952221] Key type .llcrypt unregistered [ 4336.953398] Key type ._llcrypt unregistered [ 4341.392226] Key type ._llcrypt registered [ 4341.394575] Key type .llcrypt registered [ 4341.625204] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4341.630672] alg: No test for adler32 (adler32-zlib) [ 4342.482276] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 4342.559602] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 4344.136157] Key type lgssc registered [ 4344.563899] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4362.374451] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4363.640779] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4369.776848] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4380.150517] Lustre: Mounted lustre-client [ 4383.038947] LustreError: 181846:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac59ec800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4383.047166] LustreError: 181846:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4383.071137] Lustre: Unmounted lustre-client [ 4392.630490] Key type lgssc unregistered [ 4392.744537] LNet: 182247:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4392.746811] LNetError: 182247:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4392.756845] LNet: Removed LNI 192.168.202.21@tcp [ 4393.001991] Key type .llcrypt unregistered [ 4393.003066] Key type ._llcrypt unregistered [ 4398.618447] Key type ._llcrypt registered [ 4398.619405] Key type .llcrypt registered [ 4399.126783] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 07:47:01 (1756813621) [ 4400.044957] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4400.066251] alg: No test for adler32 (adler32-zlib) [ 4400.940052] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 4401.036918] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 4402.632146] Key type lgssc registered [ 4403.050082] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4421.482592] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4422.815073] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4428.929562] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4444.658150] Lustre: Mounted lustre-client [ 4451.589274] LustreError: 185495:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac969a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4451.595595] LustreError: 185495:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4451.614121] Lustre: Unmounted lustre-client [ 4477.206655] Key type lgssc unregistered [ 4477.326570] LNet: 185899:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4477.328866] LNetError: 185899:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4477.339469] LNet: Removed LNI 192.168.202.21@tcp [ 4477.587981] Key type .llcrypt unregistered [ 4477.588983] Key type ._llcrypt unregistered [ 4482.146407] Key type ._llcrypt registered [ 4482.147745] Key type .llcrypt registered [ 4482.383568] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4482.390317] alg: No test for adler32 (adler32-zlib) [ 4483.256751] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 4483.349991] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 4484.944190] Key type lgssc registered [ 4485.391172] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4503.221702] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4504.507500] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4510.837278] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4521.459633] Lustre: Mounted lustre-client [ 4524.289391] LustreError: 188463:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac493f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4524.298073] LustreError: 188463:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4524.324143] Lustre: Unmounted lustre-client [ 4533.747222] Key type lgssc unregistered [ 4533.881499] LNet: 188864:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4533.884070] LNetError: 188864:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4533.892103] LNet: Removed LNI 192.168.202.21@tcp [ 4534.175359] Key type .llcrypt unregistered [ 4534.177188] Key type ._llcrypt unregistered [ 4539.662583] Key type ._llcrypt registered [ 4539.663780] Key type .llcrypt registered [ 4540.182821] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 07:49:22 (1756813762) [ 4547.329486] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4547.373201] alg: No test for adler32 (adler32-zlib) [ 4548.245259] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 4548.330884] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 4549.912143] Key type lgssc registered [ 4550.282432] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4580.106747] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4581.339763] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4588.862413] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4612.898657] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 07:50:35 (1756813835) [ 4622.818849] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4624.062334] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4630.559619] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4641.280921] Lustre: Mounted lustre-client [ 4643.208553] LustreError: 195361:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac59ee800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4643.216367] LustreError: 195361:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4643.237943] Lustre: Unmounted lustre-client [ 4661.693832] Key type lgssc unregistered [ 4661.860129] LNet: 195764:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4661.862616] LNetError: 195764:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4661.873107] LNet: Removed LNI 192.168.202.21@tcp [ 4662.234931] Key type .llcrypt unregistered [ 4662.236181] Key type ._llcrypt unregistered [ 4667.595615] Key type ._llcrypt registered [ 4667.596624] Key type .llcrypt registered [ 4668.087238] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 4668.675338] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 07:51:31 (1756813891) [ 4675.606798] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4675.619986] alg: No test for adler32 (adler32-zlib) [ 4676.515882] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 4676.633860] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 4678.240216] Key type lgssc registered [ 4678.677044] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4712.566734] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4713.860428] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4721.504240] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid [ 4727.740057] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid [ 4728.918281] Lustre: Mounted lustre-client [ 4734.999381] LustreError: 201286:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac87c3000: lov tgt 1000 not cleaned! deathrow=0, lovrc=1 [ 4735.006832] LustreError: 201286:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4735.029960] Lustre: Unmounted lustre-client [ 4784.946448] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4786.373304] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4794.137681] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4811.514940] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 07:53:53 (1756814033) [ 4812.002235] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 4812.600566] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 07:53:54 (1756814034) [ 4830.916831] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4832.082816] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4845.804187] Key type lgssc unregistered [ 4845.925443] LNet: 207439:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4845.927698] LNetError: 207439:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4845.934851] LNet: Removed LNI 192.168.202.21@tcp [ 4846.182463] Key type .llcrypt unregistered [ 4846.183486] Key type ._llcrypt unregistered [ 4851.561675] Key type ._llcrypt registered [ 4851.562560] Key type .llcrypt registered [ 4852.039966] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 07:54:34 (1756814074) [ 4864.144246] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4864.156838] alg: No test for adler32 (adler32-zlib) [ 4865.006642] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 4865.087805] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 4866.672145] Key type lgssc registered [ 4867.081663] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4882.084695] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4883.367678] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4889.947991] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4903.722589] Key type lgssc unregistered [ 4903.857533] LNet: 211061:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4903.861587] LNetError: 211061:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4903.874549] LNet: Removed LNI 192.168.202.21@tcp [ 4904.163528] Key type .llcrypt unregistered [ 4904.164608] Key type ._llcrypt unregistered [ 4909.853037] Key type ._llcrypt registered [ 4909.854877] Key type .llcrypt registered [ 4910.377376] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 07:55:32 (1756814132) [ 4911.417957] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4911.427644] alg: No test for adler32 (adler32-zlib) [ 4912.291400] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 4912.383967] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 4913.976156] Key type lgssc registered [ 4914.341049] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4933.813427] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4935.286932] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4941.847845] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 4952.562671] Lustre: Mounted lustre-client [ 4957.667593] Lustre: lustre-MDT0000-mdc-ffff942ac4077800: Connection to lustre-MDT0000 (at 192.168.202.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4962.786508] Lustre: lustre-MDT0001-mdc-ffff942ac4077800: Connection to lustre-MDT0001 (at 192.168.202.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4978.146181] LustreError: MGC192.168.202.121@tcp: Connection to MGS (at 192.168.202.121@tcp) was lost; in progress operations using this service will fail [ 4978.151144] Lustre: Evicted from MGS (at 192.168.202.121@tcp) after server handle changed from 0x2e6d7a6daf3b805f to 0x2e6d7a6daf3b99a3 [ 4978.154109] Lustre: MGC192.168.202.121@tcp: Connection restored to 192.168.202.121@tcp (at 192.168.202.121@tcp) [ 4995.517521] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 4997.604572] LustreError: lustre-MDT0000-mdc-ffff942ac4077800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 4997.625251] Lustre: lustre-MDT0000-mdc-ffff942ac4077800: Connection restored to 192.168.202.121@tcp (at 192.168.202.121@tcp) [ 4998.145388] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 2 sec [ 4999.336934] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 4999.866271] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 5000.458102] LustreError: 215473:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac4077800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5000.462156] LustreError: 215473:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5000.482109] Lustre: Unmounted lustre-client [ 5018.096782] Key type lgssc unregistered [ 5018.213660] LNet: 215875:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5018.216127] LNetError: 215875:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5018.225852] LNet: Removed LNI 192.168.202.21@tcp [ 5018.487059] Key type .llcrypt unregistered [ 5018.488686] Key type ._llcrypt unregistered [ 5024.022504] Key type ._llcrypt registered [ 5024.023497] Key type .llcrypt registered [ 5024.617639] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 07:57:26 (1756814246) [ 5025.608136] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5025.631240] alg: No test for adler32 (adler32-zlib) [ 5026.484381] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5026.577156] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 5028.160137] Key type lgssc registered [ 5028.562777] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5046.577142] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5047.810442] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5069.585154] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5070.808400] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5080.572488] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5087.465024] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5121.347856] Key type lgssc unregistered [ 5121.460308] LNet: 220973:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5121.462171] LNetError: 220973:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5121.467305] LNet: Removed LNI 192.168.202.21@tcp [ 5121.678549] Key type .llcrypt unregistered [ 5121.679881] Key type ._llcrypt unregistered [ 5138.158546] Key type ._llcrypt registered [ 5138.159659] Key type .llcrypt registered [ 5138.315202] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5138.326063] alg: No test for adler32 (adler32-zlib) [ 5139.182624] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5139.272735] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 5140.848110] Key type lgssc registered [ 5141.233510] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5173.703656] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5175.003345] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5182.297876] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5203.046496] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 08:00:25 (1756814425) [ 5240.042571] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5241.338377] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5249.066969] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5272.402153] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 08:01:34 (1756814494) [ 5274.802848] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 08:01:37 (1756814497) [ 5285.231365] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5286.413974] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5292.634707] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5303.280645] Lustre: Mounted lustre-client [ 5306.471778] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [ 5307.194983] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 5307.773349] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 5308.334147] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [ 5313.507142] Lustre: lustre-MDT0000-mdc-ffff942ac6f4d000: Connection to lustre-MDT0000 (at 192.168.202.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5323.748066] LustreError: MGC192.168.202.121@tcp: Connection to MGS (at 192.168.202.121@tcp) was lost; in progress operations using this service will fail [ 5323.755158] Lustre: Evicted from MGS (at 192.168.202.121@tcp) after server handle changed from 0x781c017e4bcbdb2 to 0x781c017e4bcc01a [ 5323.758321] Lustre: MGC192.168.202.121@tcp: Connection restored to 192.168.202.121@tcp (at 192.168.202.121@tcp) [ 5327.508880] Lustre: lustre-MDT0000-mdc-ffff942ac6f4d000: Connection restored to 192.168.202.121@tcp (at 192.168.202.121@tcp) [ 5328.633265] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5329.161528] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 5330.986823] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [ 5333.987947] Lustre: lustre-MDT0000-mdc-ffff942ac6f4d000: Connection to lustre-MDT0000 (at 192.168.202.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5347.296131] Lustre: 232137:0:(client.c:2470:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1756814554/real 1756814554] req@000000008ef1ed4a x1842153376462848/t0(0) o101->MGC192.168.202.121@tcp@192.168.202.121@tcp:26/25 lens 328/344 e 0 to 1 dl 1756814570 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 5347.302792] LustreError: MGC192.168.202.121@tcp: Connection to MGS (at 192.168.202.121@tcp) was lost; in progress operations using this service will fail [ 5347.309650] Lustre: 232137:0:(mgc_request.c:1900:mgc_process_log()) MGC192.168.202.121@tcp: IR log lustre-cliir failed, not fatal: rc = -5 [ 5347.313669] Lustre: Evicted from MGS (at 192.168.202.121@tcp) after server handle changed from 0x781c017e4bcc01a to 0x781c017e4bcc300 [ 5347.317200] Lustre: MGC192.168.202.121@tcp: Connection restored to 192.168.202.121@tcp (at 192.168.202.121@tcp) [ 5350.036700] Lustre: lustre-MDT0000-mdc-ffff942ac6f4d000: Connection restored to 192.168.202.121@tcp (at 192.168.202.121@tcp) [ 5351.158764] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5351.744808] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 5353.311237] LustreError: 234578:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac6f4d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5353.317152] LustreError: 234578:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5353.335094] Lustre: Unmounted lustre-client [ 5386.172328] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5387.382967] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5393.443671] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5409.266923] Lustre: Mounted lustre-client [ 5409.582874] LustreError: 236197:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac49d4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5409.589505] LustreError: 236197:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5409.591036] LustreError: 236197:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5409.611649] Lustre: Unmounted lustre-client [ 5427.451983] Key type lgssc unregistered [ 5427.573673] LNet: 236600:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5427.578604] LNetError: 236600:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5427.588034] LNet: Removed LNI 192.168.202.21@tcp [ 5427.881299] Key type .llcrypt unregistered [ 5427.882208] Key type ._llcrypt unregistered [ 5433.378845] Key type ._llcrypt registered [ 5433.379890] Key type .llcrypt registered [ 5433.846177] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 08:04:16 (1756814656) [ 5435.205110] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5435.250791] alg: No test for adler32 (adler32-zlib) [ 5436.123608] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5436.204718] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 5437.792170] Key type lgssc registered [ 5438.204696] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5455.110123] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5456.256660] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5461.887108] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5477.361521] Lustre: Mounted lustre-client [ 5480.323692] LustreError: 239761:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac6f4c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5480.331666] LustreError: 239761:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5480.353051] Lustre: Unmounted lustre-client [ 5511.946096] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5513.311367] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5519.556186] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5535.221479] Lustre: Mounted lustre-client [ 5541.375204] LustreError: 241359:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac3064000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5541.380872] LustreError: 241359:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5541.382546] LustreError: 241359:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5541.400486] Lustre: Unmounted lustre-client [ 5574.135504] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5575.256548] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5581.061983] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5596.657530] Lustre: Mounted lustre-client [ 5596.975406] LustreError: 242973:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac2bf0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5596.982302] LustreError: 242973:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5596.984077] LustreError: 242973:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5597.004154] Lustre: Unmounted lustre-client [ 5630.987372] Key type lgssc unregistered [ 5631.131691] LNet: 243378:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5631.137175] LNetError: 243378:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5631.148954] LNet: Removed LNI 192.168.202.21@tcp [ 5631.411916] Key type .llcrypt unregistered [ 5631.412864] Key type ._llcrypt unregistered [ 5636.720889] Key type ._llcrypt registered [ 5636.721820] Key type .llcrypt registered [ 5637.172931] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 08:07:39 (1756814859) [ 5638.200240] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5638.207913] alg: No test for adler32 (adler32-zlib) [ 5639.069747] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5639.151985] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 5640.736206] Key type lgssc registered [ 5641.083462] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5656.574179] Key type lgssc unregistered [ 5656.715457] LNet: 245686:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5656.717354] LNetError: 245686:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5656.727399] LNet: Removed LNI 192.168.202.21@tcp [ 5656.982379] Key type .llcrypt unregistered [ 5656.983421] Key type ._llcrypt unregistered [ 5666.437915] Key type ._llcrypt registered [ 5666.445332] Key type .llcrypt registered [ 5666.685073] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5666.690286] alg: No test for adler32 (adler32-zlib) [ 5667.558474] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5667.646375] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 5669.232133] Key type lgssc registered [ 5669.589383] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5698.293510] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5699.397508] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5706.776198] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5727.560511] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [ 5728.071208] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 08:09:10 (1756814950) [ 5737.363211] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5738.834505] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5745.975274] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5754.153291] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 5755.358273] Lustre: Mounted lustre-client [ 5760.482831] Lustre: lustre-OST0001-osc-ffff942ac81a7000: Connection to lustre-OST0001 (at 192.168.202.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5763.179321] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5773.418994] LustreError: 252164:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 5773.434180] Lustre: Unmounted lustre-client [ 5797.077033] Key type lgssc unregistered [ 5797.201805] LNet: 252568:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5797.205059] LNetError: 252568:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5797.214425] LNet: Removed LNI 192.168.202.21@tcp [ 5797.475706] Key type .llcrypt unregistered [ 5797.476965] Key type ._llcrypt unregistered [ 5812.000662] Key type ._llcrypt registered [ 5812.002530] Key type .llcrypt registered [ 5812.221353] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5812.230379] alg: No test for adler32 (adler32-zlib) [ 5813.096439] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5813.179289] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 5814.768119] Key type lgssc registered [ 5815.152585] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5844.707320] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5845.876852] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5853.350178] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5874.711644] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 08:11:37 (1756815097) [ 5888.147339] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5889.440598] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5900.777336] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 08:12:03 (1756815123) [ 5910.771288] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5912.019519] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 5918.131899] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 5933.552778] Lustre: Mounted lustre-client [ 5941.735273] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 5946.867569] LustreError: 260408:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5946.884384] Lustre: Unmounted lustre-client [ 5995.715538] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 5996.888393] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6026.876824] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6037.489726] Lustre: Mounted lustre-client [ 6037.908994] LustreError: 262468:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac4aa1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6037.915632] LustreError: 262468:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6037.918561] LustreError: 262468:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 6037.941512] Lustre: Unmounted lustre-client [ 6055.193640] Key type lgssc unregistered [ 6055.319586] LNet: 262871:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6055.323604] LNetError: 262871:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6055.334450] LNet: Removed LNI 192.168.202.21@tcp [ 6055.599403] Key type .llcrypt unregistered [ 6055.600382] Key type ._llcrypt unregistered [ 6065.553924] Key type ._llcrypt registered [ 6065.555037] Key type .llcrypt registered [ 6065.719350] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6065.732257] alg: No test for adler32 (adler32-zlib) [ 6066.581424] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6066.660259] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 6068.240147] Key type lgssc registered [ 6068.743356] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6097.693911] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6098.828936] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6106.235522] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6135.467119] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [ 6136.013743] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 08:15:58 (1756815358) [ 6145.747814] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6147.016163] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6152.984690] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6164.463626] Lustre: Mounted lustre-client [ 6165.248637] LustreError: 268904:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac44d9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6165.254110] LustreError: 268904:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6165.274101] Lustre: Unmounted lustre-client [ 6182.470288] Key type lgssc unregistered [ 6182.609669] LNet: 269306:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6182.613323] LNetError: 269306:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6182.621097] LNet: Removed LNI 192.168.202.21@tcp [ 6182.904556] Key type .llcrypt unregistered [ 6182.905898] Key type ._llcrypt unregistered [ 6187.672619] Key type ._llcrypt registered [ 6187.673514] Key type .llcrypt registered [ 6188.109465] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [ 6188.618862] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 08:16:51 (1756815411) [ 6191.409200] Key type .llcrypt unregistered [ 6191.410224] Key type ._llcrypt unregistered [ 6193.950353] Key type ._llcrypt registered [ 6193.951318] Key type .llcrypt registered [ 6194.144726] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6194.155534] alg: No test for adler32 (adler32-zlib) [ 6195.002908] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6195.082419] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 6196.656148] Key type lgssc registered [ 6197.023527] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6213.698948] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6219.107531] Lustre: Mounted lustre-client [ 6219.558429] LustreError: 272314:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac4116000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6219.565810] LustreError: 272314:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6219.583556] Lustre: Unmounted lustre-client [ 6237.650469] Key type lgssc unregistered [ 6237.773782] LNet: 272717:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6237.776253] LNetError: 272717:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6237.784546] LNet: Removed LNI 192.168.202.21@tcp [ 6238.068200] Key type .llcrypt unregistered [ 6238.069404] Key type ._llcrypt unregistered [ 6243.221616] Key type ._llcrypt registered [ 6243.222503] Key type .llcrypt registered [ 6243.674238] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 08:17:46 (1756815466) [ 6244.588221] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6244.602281] alg: No test for adler32 (adler32-zlib) [ 6245.500424] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6245.614963] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 6247.200246] Key type lgssc registered [ 6247.603107] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6313.648740] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6323.341733] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6324.547458] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6325.725733] Lustre: Mounted lustre-client [ 6326.199700] LustreError: 275756:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac4113000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6326.206756] LustreError: 275756:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6326.227162] Lustre: Unmounted lustre-client [ 6343.691753] Key type lgssc unregistered [ 6343.810614] LNet: 276160:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6343.812834] LNetError: 276160:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6343.820481] LNet: Removed LNI 192.168.202.21@tcp [ 6344.110087] Key type .llcrypt unregistered [ 6344.111215] Key type ._llcrypt unregistered [ 6349.705318] Key type ._llcrypt registered [ 6349.707136] Key type .llcrypt registered [ 6350.148066] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 08:19:32 (1756815572) [ 6350.914480] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6350.941390] alg: No test for adler32 (adler32-zlib) [ 6351.805069] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6351.893768] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 6353.488113] Key type lgssc registered [ 6353.887247] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6370.125858] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6371.278305] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6376.881126] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6392.306927] Lustre: Mounted lustre-client [ 6397.411284] Lustre: lustre-MDT0000-mdc-ffff942ac5f5a800: Connection to lustre-MDT0000 (at 192.168.202.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6399.964291] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6399.971953] LustreError: 279224:0:(file.c:6077:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [ 6400.081247] LustreError: 279243:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac5f5a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6400.081978] LustreError: MGC192.168.202.121@tcp: Connection to MGS (at 192.168.202.121@tcp) was lost; in progress operations using this service will fail [ 6400.088774] LustreError: 279243:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6400.107336] Lustre: Unmounted lustre-client [ 6410.518231] Key type lgssc unregistered [ 6410.638400] LNet: 279636:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6410.640328] LNetError: 279636:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6410.647265] LNet: Removed LNI 192.168.202.21@tcp [ 6410.867445] Key type .llcrypt unregistered [ 6410.868511] Key type ._llcrypt unregistered [ 6415.748400] Key type ._llcrypt registered [ 6415.749441] Key type .llcrypt registered [ 6416.184855] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 08:20:38 (1756815638) [ 6417.036140] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6417.053067] alg: No test for adler32 (adler32-zlib) [ 6417.912571] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6417.994540] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 6419.576174] Key type lgssc registered [ 6419.965195] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6436.189388] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6437.367405] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6442.991155] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6444.137674] Lustre: Mounted lustre-client [ 6449.636339] Lustre: lustre-MDT0001-mdc-ffff942ac4a48000: Connection to lustre-MDT0001 (at 192.168.202.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6451.530285] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 6451.845078] LustreError: 282725:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac4a48000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6451.849795] LustreError: 282725:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6451.865710] Lustre: Unmounted lustre-client [ 6462.418202] Key type lgssc unregistered [ 6462.541570] LNet: 283119:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6462.543603] LNetError: 283119:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6462.551453] LNet: Removed LNI 192.168.202.21@tcp [ 6462.765509] Key type .llcrypt unregistered [ 6462.766345] Key type ._llcrypt unregistered [ 6467.433124] Key type ._llcrypt registered [ 6467.433974] Key type .llcrypt registered [ 6467.817285] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 08:21:30 (1756815690) [ 6473.281664] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6473.289731] alg: No test for adler32 (adler32-zlib) [ 6474.127284] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6474.196893] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 6475.776187] Key type lgssc registered [ 6476.087056] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6510.095257] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6511.272293] Lustre: Mounted lustre-client [ 6530.019863] Lustre: lustre-MDT0001-mdc-ffff942ad816e000: Connection to lustre-MDT0001 (at 192.168.202.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6534.394926] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6544.686164] LustreError: 288450:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 6544.704397] Lustre: Unmounted lustre-client [ 6561.796368] Key type lgssc unregistered [ 6561.922681] LNet: 288850:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6561.924874] LNetError: 288850:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6561.933589] LNet: Removed LNI 192.168.202.21@tcp [ 6562.198574] Key type .llcrypt unregistered [ 6562.199490] Key type ._llcrypt unregistered [ 6567.394570] Key type ._llcrypt registered [ 6567.396031] Key type .llcrypt registered [ 6567.858465] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 08:23:10 (1756815790) [ 6568.373987] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [ 6568.908885] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 08:23:11 (1756815791) [ 6569.455781] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [ 6570.002096] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 08:23:12 (1756815792) [ 6570.542606] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [ 6571.131590] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 08:23:13 (1756815793) [ 6571.732153] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [ 6572.249146] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 08:23:14 (1756815794) [ 6572.812633] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [ 6573.390319] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 08:23:15 (1756815795) [ 6576.189349] Key type .llcrypt unregistered [ 6576.190396] Key type ._llcrypt unregistered [ 6578.365918] Key type ._llcrypt registered [ 6578.366812] Key type .llcrypt registered [ 6578.521225] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6578.532792] alg: No test for adler32 (adler32-zlib) [ 6579.377299] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6579.451466] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 6581.032107] Key type lgssc registered [ 6581.372816] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6605.965593] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6606.995308] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6613.668675] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6614.822744] Lustre: Mounted lustre-client [ 6615.190716] LustreError: 294437:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac3c94800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6615.197313] LustreError: 294437:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6615.218439] Lustre: Unmounted lustre-client [ 6637.864552] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 08:24:20 (1756815860) [ 6647.578622] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6648.651876] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6654.389482] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6654.554906] Lustre: Mounted lustre-client [ 6654.859215] LustreError: 296543:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac4a4c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6654.864410] LustreError: 296543:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6654.865816] LustreError: 296543:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 6654.883150] Lustre: Unmounted lustre-client [ 6673.173673] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 08:24:55 (1756815895) [ 6699.272082] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6700.384309] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6706.005568] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 6743.099290] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 08:26:05 (1756815965) [ 6746.846602] Key type lgssc unregistered [ 6746.969453] LNet: 301981:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6746.971891] LNetError: 301981:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6784.870743] LNet: Removed LNI 192.168.202.21@tcp [ 6785.111446] Key type .llcrypt unregistered [ 6785.112434] Key type ._llcrypt unregistered [ 6918.874589] Key type ._llcrypt registered [ 6918.875762] Key type .llcrypt registered [ 6919.028533] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6919.038533] alg: No test for adler32 (adler32-zlib) [ 6919.887534] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6919.966391] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 6921.544159] Key type lgssc registered [ 6921.867821] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6929.456978] Lustre: DEBUG MARKER: SKIP: conf-sanity test_73c unable to configure net [ 6933.260301] Key type lgssc unregistered [ 6933.384398] LNet: 303667:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6933.388176] LNetError: 303667:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6933.395458] LNet: Removed LNI 192.168.202.21@tcp [ 6933.616335] Key type .llcrypt unregistered [ 6933.617366] Key type ._llcrypt unregistered [ 6937.034692] Key type ._llcrypt registered [ 6937.035739] Key type .llcrypt registered [ 6937.429110] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 08:29:19 (1756816159) [ 6943.696171] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6943.717260] alg: No test for adler32 (adler32-zlib) [ 6944.565343] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6944.637881] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 6946.216126] Key type lgssc registered [ 6946.527298] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6961.654188] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 08:29:44 (1756816184) [ 6965.422181] Key type lgssc unregistered [ 6965.536350] LNet: 307208:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6965.538197] LNetError: 307208:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6965.543494] LNet: Removed LNI 192.168.202.21@tcp [ 6965.765482] Key type .llcrypt unregistered [ 6965.766398] Key type ._llcrypt unregistered [ 6969.232356] Key type ._llcrypt registered [ 6969.234276] Key type .llcrypt registered [ 6969.411567] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6969.416686] alg: No test for adler32 (adler32-zlib) [ 6970.255509] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 6970.326812] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 6971.904118] Key type lgssc registered [ 6972.238456] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6991.433253] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 6992.497411] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 6999.354884] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7002.289971] LNet: Added LNI 192.168.202.21@tcp42 [8/256/0/180] [ 7002.898721] LNet: Removed LNI 192.168.202.21@tcp [ 7004.268286] Lustre: 310098:0:(ldlm_lib.c:532:client_obd_setup()) lustre-MDT0000-mdc-ffff942ac4111800: config has no valid NIDs, force dynamic NIDs [ 7005.306313] Lustre: Mounted lustre-client [ 7005.327147] LustreError: 310113:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac4111800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7005.332632] LustreError: 310113:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7005.350885] Lustre: Unmounted lustre-client [ 7023.720969] Key type lgssc unregistered [ 7023.865113] LNet: 310951:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7023.867157] LNetError: 310951:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7023.876320] LNet: Removed LNI 192.168.202.21@tcp42 [ 7024.134340] Key type .llcrypt unregistered [ 7024.135721] Key type ._llcrypt unregistered [ 7027.836471] Key type ._llcrypt registered [ 7027.837432] Key type .llcrypt registered [ 7028.283431] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 08:30:50 (1756816250) [ 7029.090616] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7029.127299] alg: No test for adler32 (adler32-zlib) [ 7029.980413] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 7030.057840] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 7031.632123] Key type lgssc registered [ 7032.032726] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7048.231197] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7049.261767] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7054.462526] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7055.607631] Lustre: Mounted lustre-client [ 7074.520709] Lustre: Mounted fs15246-client [ 7078.864797] LustreError: 315071:0:(lov_obd.c:784:lov_cleanup()) fs15246-clilov-ffff942ac91bd000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7078.869958] LustreError: 315071:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 7078.880209] Lustre: Unmounted fs15246-client [ 7090.995088] LustreError: 315290:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff942ac4111800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7090.998827] LustreError: 315290:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7091.000450] LustreError: 315290:0:(obd_class.h:479:obd_check_dev()) Skipped 3 previous similar messages [ 7091.019117] Lustre: Unmounted lustre-client [ 7116.491819] Key type lgssc unregistered [ 7116.602611] LNet: 315692:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7116.604908] LNetError: 315692:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7116.612816] LNet: Removed LNI 192.168.202.21@tcp [ 7116.823326] Key type .llcrypt unregistered [ 7116.824401] Key type ._llcrypt unregistered [ 7120.376928] Key type ._llcrypt registered [ 7120.377932] Key type .llcrypt registered [ 7120.807972] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 08:32:23 (1756816343) [ 7121.347584] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7121.360588] alg: No test for adler32 (adler32-zlib) [ 7122.215938] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 7122.298235] LNet: Added LNI 192.168.202.21@tcp [8/256/0/180] [ 7123.880176] Key type lgssc registered [ 7124.243094] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7166.672475] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 7167.786875] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 7174.498319] Lustre: DEBUG MARKER: oleg221-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 7215.857997] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 7023 sec ========== 08:33:58 (1756816438) [ 7216.391493] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 08:33:58 (1756816438) === [ 7217.356972] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 08:33:59 (1756816439) === [ 7227.019867] Key type lgssc unregistered [ 7227.133294] LNet: 324825:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7227.135232] LNetError: 324825:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7227.142489] LNet: Removed LNI 192.168.202.21@tcp [ 7227.342396] Key type .llcrypt unregistered [ 7227.343452] Key type ._llcrypt unregistered